Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.08.2021 04: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 58e1ddde69c44dad93d327777e5becec
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 2021-05-09 08:45
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 4793.92
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 16161948 kB
MemAvailable 30949320 kB
Buffers 20 kB
Cached 15451136 kB
SwapCached 2600 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.1
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-08-18 04:37:04
DTS_END                         2021-08-18 04:37:04.0690
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/48106
TRN_ID                          226993
ATT_ID                          640
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: 8823.32 20818 1058798
customer order: creation 164.40 405 19728
customer order: refuse 13.48 319 1618
order to supplier: creation 43.66 1454 5239
order to supplier: removal 3.26 121440 391
invoice (draft): creation 43.41 4097 5209
invoice (draft): removal 50.36 1158 6043
invoice accept: apply 39.19 20208 4703
invoice accept: cancel 44.76 941 5371
customer reserve: creation 3731.68 193 447802
customer reserve: removal 4413.11 68 529573
realization accept: apply 79.46 194 9535
realization accept: cancel 76.17 123 9140
payment from customer: creation 55.09 137 6611
payment from customer: removal 5.13 65 616
payment to supplier: creation 46.22 165 5546
payment to supplier: removal 3.39 63 407
service: total inventory turnovers 3.54 3991 425
service: total monetary turnovers 6.09 545 731
service: aggregate perf. data 0.88 15800 106
service: refresh index statistics 0.03 245002 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-08-2021 04:38:07 to 18-08-2021 04:38:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2474
TEST_TIME 2 4216
TEST_TIME 3 4863
TEST_TIME 4 5202
TEST_TIME 5 5251
TEST_TIME 6 5117
TEST_TIME 7 6146
TEST_TIME 8 6261
TEST_TIME 9 6479
TEST_TIME 10 6675
TEST_TIME 11 6930
TEST_TIME 12 7129
TEST_TIME 13 7289
TEST_TIME 14 7587
TEST_TIME 15 7600
TEST_TIME 16 7666
TEST_TIME 17 7666
TEST_TIME 18 7562
TEST_TIME 19 8564
TEST_TIME 20 8724
TEST_TIME 21 8825
TEST_TIME 22 8983
TEST_TIME 23 8971
TEST_TIME 24 8922
TEST_TIME 25 8815
TEST_TIME 26 8832
TEST_TIME 27 9699
TEST_TIME 28 11091
TEST_TIME 29 10947
TEST_TIME 30 10765
TEST_TIME 31 10886
TEST_TIME 32 10793
TEST_TIME 33 10541
TEST_TIME 34 10400
TEST_TIME 35 10234
TEST_TIME 36 10209
TEST_TIME 37 10085
TEST_TIME 38 9972
TEST_TIME 39 9831
TEST_TIME 40 9827
TEST_TIME 41 9768
TEST_TIME 42 9688
TEST_TIME 43 9534
TEST_TIME 44 9416
TEST_TIME 45 9321
TEST_TIME 46 9311
TEST_TIME 47 9249
TEST_TIME 48 9353
TEST_TIME 49 9284
TEST_TIME 50 9335
TEST_TIME 51 9264
TEST_TIME 52 9256
TEST_TIME 53 9320
TEST_TIME 54 9284
TEST_TIME 55 9242
TEST_TIME 56 9140
TEST_TIME 57 9214
TEST_TIME 58 9227
TEST_TIME 59 9304
TEST_TIME 60 9296
TEST_TIME 61 9267
TEST_TIME 62 9275
TEST_TIME 63 9407
TEST_TIME 64 9401
TEST_TIME 65 9457
TEST_TIME 66 9352
TEST_TIME 67 9236
TEST_TIME 68 9234
TEST_TIME 69 9177
TEST_TIME 70 9183
TEST_TIME 71 9116
TEST_TIME 72 9058
TEST_TIME 73 8999
TEST_TIME 74 8916
TEST_TIME 75 8930
TEST_TIME 76 8928
TEST_TIME 77 9020
TEST_TIME 78 9076
TEST_TIME 79 9055
TEST_TIME 80 9032
TEST_TIME 81 9054
TEST_TIME 82 9077
TEST_TIME 83 9062
TEST_TIME 84 9058
TEST_TIME 85 9107
TEST_TIME 86 9096
TEST_TIME 87 9203
TEST_TIME 88 9124
TEST_TIME 89 9027
TEST_TIME 90 8986
TEST_TIME 91 8982
TEST_TIME 92 8953
TEST_TIME 93 8936
TEST_TIME 94 8941
TEST_TIME 95 8987
TEST_TIME 96 8961
TEST_TIME 97 8989
TEST_TIME 98 8962
TEST_TIME 99 8980
TEST_TIME 100 8995
TEST_TIME 101 9033
TEST_TIME 102 9055
TEST_TIME 103 9056
TEST_TIME 104 9109
TEST_TIME 105 9112
TEST_TIME 106 9128
TEST_TIME 107 9069
TEST_TIME 108 9044
TEST_TIME 109 9003
TEST_TIME 110 8980
TEST_TIME 111 8937
TEST_TIME 112 8940
TEST_TIME 113 8904
TEST_TIME 114 8895
TEST_TIME 115 8893
TEST_TIME 116 8861
TEST_TIME 117 8866
TEST_TIME 118 8828
TEST_TIME 119 8895
TEST_TIME 120 8823

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-08-2021 04:38:10 to 18-08-2021 04:38:14.

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 1472432 1472432 0 0.00 0 42992 7 0 0
doc_list_biud 1472432 1472432 0 0.00 0 44873 32 0 0
sp_add_invoice_to_stock 4765 4703 62 1.30 58 179254 20209 0 62
sp_cancel_adding_invoice 5371 5371 0 0.00 4 73966 942 0 0
sp_cancel_client_order 1618 1618 0 0.00 4 7702 319 0 0
sp_cancel_customer_reserve 529573 529573 0 0.00 1 29658 68 0 0
sp_cancel_pay_from_customer 616 616 0 0.00 3 1821 66 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 1207 63 0 0
sp_cancel_supplier_invoice 6047 6043 4 0.06 2 23369 1159 0 4
sp_cancel_supplier_order 391 391 0 0.00 9 1039971 121441 0 0
sp_cancel_write_off 9140 9140 0 0.00 1 19401 123 0 0
sp_client_order 19728 19728 0 0.00 4 31233 406 0 0
sp_customer_reserve 447802 447802 0 0.00 0 153341 193 0 0
sp_fill_shopping_cart 30166 30166 0 0.00 1 16685 48 0 0
sp_fill_shopping_cart_clo_res 447800 447800 0 0.00 0 13698 15 0 0
sp_get_clo_for_invoice 4701 4701 0 0.00 0 20145 389 0 0
sp_kill_cost_storno 15534 15534 0 0.00 0 8084 69 0 0
sp_kill_qstorno_ret_qs2qd 1074720 1074720 0 0.00 0 29649 38 0 0
sp_kill_qty_storno 566372 566372 0 0.00 0 44873 82 0 0
sp_lock_selected_doc 19623 19623 0 0.00 0 7334 4 0 0
sp_make_cost_storno 26393 26393 0 0.00 0 42989 62 0 0
sp_multiply_rows_for_pdistr 26393 26393 0 0.00 0 13139 36 0 0
sp_multiply_rows_for_qdistr 19728 19728 0 0.00 0 20097 184 0 0
sp_pay_from_customer 6611 6611 0 0.00 4 21242 137 0 0
sp_pay_to_supplier 5546 5546 0 0.00 5 42996 166 0 0
sp_payment_common 12157 12157 0 0.00 1 10928 22 0 0
sp_qd_handle_on_cancel_clo 1618 1618 0 0.00 0 7693 290 0 0
sp_qd_handle_on_invoice_upd_sts 10072 10072 0 0.00 0 44872 394 0 0
sp_qd_handle_on_reserve_upd_sts 9535 9535 0 0.00 0 16925 69 0 0
sp_reserve_write_off 9535 9535 0 0.00 6 17023 195 0 0
sp_supplier_invoice 5209 5209 0 0.00 8 84147 4097 0 0
sp_supplier_order 5239 5239 0 0.00 6 35537 1455 0 0
srv_aggregate_perf_data 106 106 0 0.00 2109 119556 15801 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1565 194858 53248 0 0
srv_find_qd_qs_mism 1012817 1012817 0 0.00 0 19992 6 0 0
srv_make_invnt_saldo 425 425 0 0.00 73 119589 3991 0 0
srv_make_money_saldo 731 731 0 0.00 1 14126 546 0 0
srv_recalc_idx_stat 4 4 0 0.00 127419 344196 245003 0 0
t$perf-abend:sp_add_invoice_to_stock 62 62 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4703 4703 0 0.00 0 18126 192 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 3646 88 0 0
t$perf-norm:sp_cancel_client_order 1618 1618 0 0.00 0 4081 15 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 2016 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 616 616 0 0.00 0 3789 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 401 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 3743 26 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 26280 1824 0 0
t$perf-norm:sp_cancel_write_off 913 913 0 0.00 0 931 5 0 0
t$perf-norm:sp_client_order 19728 19728 0 0.00 0 6874 19 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 1838 6 0 0
t$perf-norm:sp_pay_from_customer 6611 6611 0 0.00 0 12862 12 0 0
t$perf-norm:sp_pay_to_supplier 5546 5546 0 0.00 0 6420 9 0 0
t$perf-norm:sp_reserve_write_off 9535 9535 0 0.00 0 6485 11 0 0
t$perf-norm:sp_supplier_invoice 5209 5209 0 0.00 0 12058 18 0 0
t$perf-norm:sp_supplier_order 5239 5239 0 0.00 0 3341 16 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 426 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 48 1 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 568 7 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 2946 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 9526 9402 124 1.30 0 6952 17 0 124
v_all_customers 35966 35966 0 0.00 0 27 1 0 0
v_all_suppliers 29298 29298 0 0.00 0 16 1 0 0
v_all_wares 39456 39456 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1480 8 0 0
v_cancel_client_order 3236 3236 0 0.00 0 2947 7 0 0
v_cancel_customer_prepayment 1232 1232 0 0.00 0 1450 6 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 2044 7 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 873 5 0 0
v_cancel_supplier_invoice 782 774 8 1.02 0 5598 21 0 8
v_cancel_supplier_order 782 782 0 0.00 0 459 4 0 0
v_cancel_write_off 1826 1826 0 0.00 0 1743 14 0 0
v_max_id_clo_ord 5239 5239 0 0.00 0 368 1 0 0
v_max_id_clo_res 951 951 0 0.00 0 2478 9 0 0
v_max_id_ord_sup 5199 5199 0 0.00 0 6794 5 0 0
v_max_non_paid_invoice 1335 1335 0 0.00 0 4993 6 0 0
v_max_non_paid_realizn 3435 3435 0 0.00 0 2824 4 0 0
v_min_id_clo_ord 5239 5239 0 0.00 0 4483 5 0 0
v_min_id_clo_res 951 951 0 0.00 0 2075 7 0 0
v_min_id_ord_sup 5199 5199 0 0.00 0 6683 12 0 0
v_min_non_paid_invoice 5546 5546 0 0.00 0 6709 14 0 0
v_min_non_paid_realizn 6611 6611 0 0.00 0 9391 15 0 0
v_our_firm 9842 9842 0 0.00 0 15 1 0 0
v_reserve_write_off 19070 19070 0 0.00 0 4240 5 0 0
x_lock_depdocs_on_canc_invoice 11027 11027 0 0.00 0 20024 129 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 7883 375 0 0
x_make_qstorno_1000_1200 5239 5239 0 0.00 2 35521 1381 0 0
x_make_qstorno_1200_2000 5209 5209 0 0.00 4 84115 3893 0 0
x_make_qstorno_2100_3300 447802 447802 0 0.00 0 153339 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-08-2021 04:38:15 to 18-08-2021 04:38:19.

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
02:07:07 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:08 15707 8192000000 9714451840 10014138368 795949376 8.855 100 83 166 0 823617584 7725936 19942272
02:09:29 18370 8192000000 9773544592 10084442112 845076128 9.351 100 83 166 0 876096736 11473840 19546768
02:10:55 28785 8192000000 9823101232 10144919552 881269776 9.712 100 81 162 0 921227024 21468208 18489040
02:12:31 13628 8192000000 9870446656 10205319168 919062256 10.087 100 77 154 0 979943136 44393952 16486928
02:13:50 11205 8192000000 9930797008 10275045376 964739072 10.535 100 80 160 1 1041305312 60199504 16366736
02:15:05 5562 8192000000 9960159440 10316517376 992866304 10.809 100 79 157 1 1072461824 63749840 15845680
02:16:12 7084 8192000000 9977146624 10346401792 1018530144 11.058 100 62 123 1 1098931216 68736368 11664704
02:17:22 1705 8192000000 10013737216 10379046912 1047888176 11.340 100 84 168 0 1131391504 65951840 17551488
02:18:25 8575 8192000000 10030285776 10402914304 1058101008 11.438 100 76 151 0 1138879744 65690080 15088656
02:19:36 3778 8192000000 10031976208 10414448640 1066565040 11.519 100 52 99 2 1135979712 59375824 10038848
02:20:41 4318 8192000000 10039316480 10430099456 1077125328 11.620 100 67 134 2 1153545024 61881632 14538064
02:21:47 36652 8192000000 9991200816 10396950528 1084041504 11.686 100 43 86 2 1113143600 19731392 9370704
02:23:27 4311 8192000000 10015879984 10412830720 1089247920 11.736 100 46 92 2 1119332416 20749760 9334736
02:24:32 6367 8192000000 10010058992 10411945984 1095153296 11.792 100 34 68 2 1127282624 24588896 7540432
02:25:41 4216 8192000000 10026921936 10422743040 1096287360 11.802 100 50 101 2 1137741440 29816960 11637120
02:26:48 67987 8192000000 9981964544 10387709952 1037535296 11.241 95 23 46 0 1060602496 18065600 5001600
02:28:57 12138 8192000000 9970137360 10367180800 1037156096 11.237 96 36 73 0 1057228224 12332496 7739632
02:30:11 3719 8192000000 9953427408 10345005056 1038352992 11.249 99 46 90 1 1067767968 18045568 11369408
02:31:16 10219 8192000000 9970511728 10361171968 1027747168 11.147 99 31 60 2 1055482416 20658400 7076848
02:32:30 40368 8192000000 9925372080 10315603968 955761424 10.448 92 27 54 1 976002752 13175568 7065760
02:34:11 7092 8192000000 9965708112 10354352128 1044673440 11.310 100 45 88 2 1074240608 19619408 9947760
02:35:23 9429 8192000000 9987670064 10383806464 1055725552 11.416 100 41 82 0 1081479904 16521456 9232896
02:36:33 20577 8192000000 9965852432 10378686464 1067010288 11.524 100 31 63 0 1089790640 15432864 7347488
02:38:31 29920 8192000000 10042775136 10441498624 1096364960 11.803 100 45 89 1 1150691072 45169616 9156496
02:40:10 13433 8192000000 10082972944 10484408320 1112382096 11.955 100 48 95 0 1190059360 67235632 10441632
02:41:34 24487 8192000000 10109114176 10527797248 1130621216 12.127 100 43 86 0 1220781424 80790608 9369600
02:43:04 5432 8192000000 10167907264 10575077376 1142017328 12.235 100 68 136 0 1269445712 111963504 15464880
02:44:12 9231 8192000000 10184526240 10607837184 1150670688 12.316 100 39 78 0 1294434704 134563600 9200416
02:45:29 6651 8192000000 10189336208 10597154816 1159562624 12.399 100 68 133 1 1290483152 116477248 14443280
02:46:39 3615 8192000000 10228688896 10637766656 1161830592 12.420 100 63 125 0 1329947184 154848016 13268576
02:47:49 2432 8192000000 10276509680 10687012864 1177058624 12.563 100 79 158 0 1379543872 184027328 18457920
02:49:02 6876 8192000000 10278263520 10699022336 1181317024 12.602 100 62 124 1 1382467504 187121920 14028560
02:50:17 12152 8192000000 10294815072 10718494720 1180946128 12.599 100 66 131 3 1390555456 194505184 15104144
02:51:37 3135 8192000000 10316723824 10740473856 1197586176 12.754 100 63 127 1 1427618656 215196976 14835504
02:52:43 1563 8192000000 10348845312 10756169728 1176535200 12.558 100 89 173 1 1443097728 245886960 20675568
02:53:49 2509 8192000000 10378263312 10788954112 1149871824 12.308 99 77 153 1 1471818000 303773216 18172960
02:54:56 2308 8192000000 10403357968 10804412416 1140506496 12.220 100 87 174 1 1498695472 337380800 20808176
02:56:04 2625 8192000000 10405234272 10823352320 1136650512 12.184 99 83 164 0 1495953024 340129488 19173024
02:57:12 6161 8192000000 10339370848 10756771840 1127088736 12.094 99 59 115 0 1431767472 290806416 13872320
02:58:25 13179 8192000000 10366039792 10813558784 1125129072 12.075 99 31 62 1 1471388384 338084416 8174896
02:59:43 12567 8192000000 10355074128 10790719488 1133181872 12.151 100 34 68 1 1456213824 314216256 8815696
03:01:09 31888 8192000000 10420666784 10849792000 1113189488 11.963 99 30 60 1 1485749904 364501952 8058464
03:02:53 16030 8192000000 10478430448 10910343168 1111253088 11.944 99 43 84 1 1562244736 439599088 11392560
03:04:18 14137 8192000000 10381312560 10879025152 1106754336 11.902 100 38 75 0 1515121888 398250336 10117216
03:05:39 83569 8192000000 10115900352 10606084096 1104788736 11.883 99 21 42 1 1224069040 114227616 5052688
03:08:08 57701 8192000000 10047075072 10518106112 1118516912 12.013 100 22 45 1 1191466736 68300496 4649328
03:10:13 62870 8192000000 10074643472 10519478272 1124418560 12.069 99 14 28 0 1153185872 26012656 2754656
03:12:21 44153 8192000000 10116432512 10547335168 1103877296 11.874 97 53 108 1 1173645376 58778496 10989584
03:14:08 135913 8192000000 10086961264 10539753472 1124972992 12.074 99 24 48 0 1174796864 44933056 4890816
03:17:34 110902 8192000000 10050448224 10519330816 1114977360 11.980 98 29 58 0 1172014736 50793344 6244032
03:20:30 44370 8192000000 10080172384 10517274624 1137089088 12.188 100 41 81 1 1192900272 47069504 8741680
03:22:18 67780 8192000000 10177493552 10635464704 1142787984 12.242 99 29 59 0 1276901088 127417024 6696080
03:24:36 167830 8192000000 10110135648 10584915968 1124946176 12.074 98 21 42 2 1244079168 114316304 4816688
03:28:24 20682 8192000000 10061149984 10491523072 1133941776 12.159 100 57 106 5 1173953984 27540960 12471248
03:29:46 110815 8192000000 10072165632 10588749824 1143907328 12.252 100 16 32 1 1222005488 74633168 3464992
03:32:42 90000 8192000000 10125397424 10613309440 1134445392 12.163 99 8 16 0 1195448192 59358480 1644320
03:35:19 100513 8192000000 10105236048 10605813760 1133324912 12.153 98 21 42 0 1199499792 61453824 4721056
03:38:04 66973 8192000000 10132054720 10581176320 1143217680 12.246 98 15 30 0 1201678288 55308880 3151728
03:40:13 128203 8192000000 10098199824 10596737024 1105006480 11.885 95 11 22 0 1179076656 71820272 2249904
03:43:31 156788 8192000000 10090550080 10580021248 1123410208 12.059 97 28 56 2 1186589264 57349504 5829552
03:47:08 59636 8192000000 10049087216 10522865664 1110615120 11.938 97 13 26 0 1161598064 48001808 2981136
03:49:23 194836 8192000000 10093848576 10574311424 1015439440 11.028 89 12 25 1 1059022736 40601216 2982080
03:54:16 40966 8192000000 10079774672 10536185856 1103455472 11.870 98 13 26 0 1156497120 50279296 2762352
03:56:24 36011 8192000000 10054586544 10501832704 1076953584 11.618 97 31 62 0 1107971040 24618128 6399328
03:58:06 45117 8192000000 10035089248 10468741120 1080851264 11.656 97 31 62 2 1124553936 37532304 6170368
03:59:52 45597 8192000000 10036531568 10454700032 1075307600 11.603 97 27 52 2 1114374288 33921248 5145440
04:01:40 6907 8192000000 10073100560 10487111680 1095059472 11.791 98 53 106 0 1149127952 43986080 10082400
04:02:49 65822 8192000000 10106274960 10556317696 1061773872 11.473 95 13 26 0 1123561104 59577648 2209584
04:05:05 129293 8192000000 10054650432 10490245120 1089933152 11.742 98 16 32 1 1123332224 30398496 3000576
04:08:18 96138 8192000000 10071969552 10577743872 1095641232 11.796 98 10 20 1 1178512320 80748624 2122464
04:11:00 45124 8192000000 10103371952 10547482624 1135695344 12.175 100 19 38 0 1192749376 52969488 4084544
04:12:45 105122 8192000000 10067193920 10509619200 1121948736 12.045 98 26 52 1 1179208784 51785760 5474288
04:15:30 65000 8192000000 10094650544 10558947328 1126973920 12.093 99 33 66 0 1192152528 58828976 6349632
04:17:41 85977 8192000000 10107808496 10540838912 1135047136 12.169 99 30 60 2 1213352384 71771344 6533904
04:20:08 35088 8192000000 10080983056 10532323328 1111609584 11.948 98 29 58 1 1164095696 46518544 5967568
04:21:46 49614 8192000000 10107795072 10556448768 1125514720 12.079 99 24 48 0 1183573296 53493184 4565392
04:23:37 116284 8192000000 10082914032 10525290496 1142153776 12.236 100 18 36 0 1204420192 58158336 4108080
04:26:42 69235 8192000000 10082150656 10547294208 1119580368 12.023 98 13 26 0 1164178720 41951120 2647232
04:28:53 49126 8192000000 10113243360 10566606848 1147172416 12.283 100 10 20 1 1214267280 65031440 2063424
04:30:43 38115 8192000000 10095585760 10563485696 1132525552 12.145 99 12 24 0 1177816464 42875568 2415344
04:32:36 72426 8192000000 10081707968 10525749248 1108825280 11.921 97 14 28 0 1149850448 38265920 2759248
04:34:53 36209 8192000000 10127148528 10578247680 1137245328 12.190 99 23 46 0 1204463952 61635072 5583552
04:36:35 37701 8192000000 9053101056 9310547968 196104608 2.337 18 3 6 0 209171760 12532432 534720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-08-2021 04:38:21 to 18-08-2021 04:38:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 62 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-08-2021 04:38:36 to 18-08-2021 04:38:38.

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          227307
MON$OLDEST_ACTIVE               227308
MON$OLDEST_SNAPSHOT             227308
MON$NEXT_TRANSACTION            227308
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                       2043488
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:185: 
:186: mscs-test-161.budzdorov.ru    Wed Aug 18 04:37:15 2021
:187:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:188: 
--- end of diff output ---

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

18.08.21 04:38:39. 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-08-18 04:38:42 No severe PSQL-related problems occured
18.08.21 04:38:43. End of report.