Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.09.2021 04:38
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 15991484 kB
MemAvailable 30759608 kB
Buffers 20 kB
Cached 15643624 kB
SwapCached 668 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-09-23 04:37:05
DTS_END                         2021-09-23 04:37:05.9180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50212
TRN_ID                          232672
ATT_ID                          692
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: 8817.92 23327 1058150
customer order: creation 166.54 434 19985
customer order: refuse 13.82 362 1658
order to supplier: creation 43.26 1475 5191
order to supplier: removal 3.28 114776 394
invoice (draft): creation 44.98 3561 5398
invoice (draft): removal 50.30 922 6036
invoice accept: apply 39.83 19618 4780
invoice accept: cancel 44.82 852 5378
customer reserve: creation 3701.07 194 444128
customer reserve: removal 4430.98 67 531718
realization accept: apply 82.12 179 9854
realization accept: cancel 73.11 126 8773
payment from customer: creation 57.69 109 6923
payment from customer: removal 5.97 102 716
payment to supplier: creation 46.25 124 5550
payment to supplier: removal 3.41 105 409
service: total inventory turnovers 3.95 3144 474
service: total monetary turnovers 5.62 485 674
service: aggregate perf. data 0.89 15987 107
service: refresh index statistics 0.03 303925 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-09-2021 04:38:33 to 23-09-2021 04:38:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8823
TEST_TIME 2 6369
TEST_TIME 3 6434
TEST_TIME 4 5457
TEST_TIME 5 5183
TEST_TIME 6 5200
TEST_TIME 7 5008
TEST_TIME 8 5386
TEST_TIME 9 5600
TEST_TIME 10 5650
TEST_TIME 11 5824
TEST_TIME 12 6177
TEST_TIME 13 6447
TEST_TIME 14 7515
TEST_TIME 15 7796
TEST_TIME 16 8164
TEST_TIME 17 8918
TEST_TIME 18 9256
TEST_TIME 19 9131
TEST_TIME 20 9373
TEST_TIME 21 9973
TEST_TIME 22 10332
TEST_TIME 23 10606
TEST_TIME 24 10643
TEST_TIME 25 10476
TEST_TIME 26 10935
TEST_TIME 27 10664
TEST_TIME 28 10356
TEST_TIME 29 10203
TEST_TIME 30 10102
TEST_TIME 31 10387
TEST_TIME 32 10125
TEST_TIME 33 10081
TEST_TIME 34 9900
TEST_TIME 35 9785
TEST_TIME 36 9587
TEST_TIME 37 9425
TEST_TIME 38 9634
TEST_TIME 39 9555
TEST_TIME 40 9558
TEST_TIME 41 9547
TEST_TIME 42 9554
TEST_TIME 43 9773
TEST_TIME 44 9675
TEST_TIME 45 9685
TEST_TIME 46 9692
TEST_TIME 47 9732
TEST_TIME 48 9680
TEST_TIME 49 9729
TEST_TIME 50 9607
TEST_TIME 51 9454
TEST_TIME 52 9344
TEST_TIME 53 9379
TEST_TIME 54 9315
TEST_TIME 55 9252
TEST_TIME 56 9161
TEST_TIME 57 9168
TEST_TIME 58 9194
TEST_TIME 59 9147
TEST_TIME 60 9073
TEST_TIME 61 9095
TEST_TIME 62 9180
TEST_TIME 63 9215
TEST_TIME 64 9327
TEST_TIME 65 9342
TEST_TIME 66 9368
TEST_TIME 67 9393
TEST_TIME 68 9348
TEST_TIME 69 9365
TEST_TIME 70 9454
TEST_TIME 71 9475
TEST_TIME 72 9454
TEST_TIME 73 9350
TEST_TIME 74 9325
TEST_TIME 75 9285
TEST_TIME 76 9270
TEST_TIME 77 9204
TEST_TIME 78 9142
TEST_TIME 79 9118
TEST_TIME 80 9078
TEST_TIME 81 9123
TEST_TIME 82 9077
TEST_TIME 83 9112
TEST_TIME 84 9091
TEST_TIME 85 9079
TEST_TIME 86 9147
TEST_TIME 87 9229
TEST_TIME 88 9224
TEST_TIME 89 9184
TEST_TIME 90 9314
TEST_TIME 91 9277
TEST_TIME 92 9324
TEST_TIME 93 9241
TEST_TIME 94 9178
TEST_TIME 95 9128
TEST_TIME 96 9170
TEST_TIME 97 9108
TEST_TIME 98 9073
TEST_TIME 99 9049
TEST_TIME 100 9091
TEST_TIME 101 9060
TEST_TIME 102 9114
TEST_TIME 103 9062
TEST_TIME 104 9086
TEST_TIME 105 9147
TEST_TIME 106 9108
TEST_TIME 107 9145
TEST_TIME 108 9135
TEST_TIME 109 9080
TEST_TIME 110 9062
TEST_TIME 111 9006
TEST_TIME 112 9011
TEST_TIME 113 8990
TEST_TIME 114 8992
TEST_TIME 115 8948
TEST_TIME 116 8903
TEST_TIME 117 8880
TEST_TIME 118 8911
TEST_TIME 119 8895
TEST_TIME 120 8825

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-09-2021 04:38:36 to 23-09-2021 04:38:40.

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 1470035 1470035 0 0.00 0 23222 7 0 0
doc_list_biud 1470034 1470034 0 0.00 0 46239 31 0 0
sp_add_invoice_to_stock 4871 4780 91 1.86 48 210049 19618 0 91
sp_cancel_adding_invoice 5378 5378 0 0.00 5 78289 853 0 0
sp_cancel_client_order 1658 1658 0 0.00 5 13748 362 0 0
sp_cancel_customer_reserve 531718 531718 0 0.00 1 46240 68 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 3 5105 102 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 3 7253 105 0 0
sp_cancel_supplier_invoice 6045 6036 9 0.14 2 27334 923 0 9
sp_cancel_supplier_order 394 394 0 0.00 18 1015842 114777 0 0
sp_cancel_write_off 8773 8773 0 0.00 1 9627 127 0 0
sp_client_order 19985 19985 0 0.00 4 22535 434 0 0
sp_customer_reserve 444128 444128 0 0.00 0 96628 194 0 0
sp_fill_shopping_cart 30561 30561 0 0.00 1 14678 46 0 0
sp_fill_shopping_cart_clo_res 444116 444116 0 0.00 0 21531 19 0 0
sp_get_clo_for_invoice 4768 4768 0 0.00 0 23082 388 0 0
sp_kill_cost_storno 15276 15276 0 0.00 0 9901 61 0 0
sp_kill_qstorno_ret_qs2qd 1078639 1078639 0 0.00 0 46233 37 0 0
sp_kill_qty_storno 568579 568579 0 0.00 0 46238 79 0 0
sp_lock_selected_doc 20171 20171 0 0.00 0 8614 6 0 0
sp_make_cost_storno 27095 27095 0 0.00 0 16445 56 0 0
sp_multiply_rows_for_pdistr 27095 27095 0 0.00 0 8101 30 0 0
sp_multiply_rows_for_qdistr 19985 19985 0 0.00 0 17721 235 0 0
sp_pay_from_customer 6923 6923 0 0.00 4 19472 109 0 0
sp_pay_to_supplier 5550 5550 0 0.00 4 14366 124 0 0
sp_payment_common 12473 12473 0 0.00 1 7857 21 0 0
sp_qd_handle_on_cancel_clo 1658 1658 0 0.00 0 13741 338 0 0
sp_qd_handle_on_invoice_upd_sts 10146 10146 0 0.00 0 30464 363 0 0
sp_qd_handle_on_reserve_upd_sts 9854 9854 0 0.00 0 11253 52 0 0
sp_reserve_write_off 9854 9854 0 0.00 6 16464 179 0 0
sp_supplier_invoice 5398 5398 0 0.00 6 114643 3561 0 0
sp_supplier_order 5191 5191 0 0.00 5 38148 1475 0 0
srv_aggregate_perf_data 107 107 0 0.00 3248 156096 15988 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1337 165632 45835 0 0
srv_find_qd_qs_mism 1012516 1012516 0 0.00 0 23221 6 0 0
srv_make_invnt_saldo 474 474 0 0.00 73 102061 3145 0 0
srv_make_money_saldo 674 674 0 0.00 1 16808 486 0 0
srv_recalc_idx_stat 4 4 0 0.00 100188 508744 303925 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4782 4782 0 0.00 0 23277 174 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 0 1624 72 0 0
t$perf-norm:sp_cancel_client_order 1658 1658 0 0.00 0 1066 9 0 0
t$perf-norm:sp_cancel_customer_reserve 621 621 0 0.00 0 1673 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 10701 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 2532 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 6541 34 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 19422 1434 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 8444 19 0 0
t$perf-norm:sp_client_order 19985 19985 0 0.00 0 11101 18 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 6077 14 0 0
t$perf-norm:sp_pay_from_customer 6923 6923 0 0.00 0 4772 10 0 0
t$perf-norm:sp_pay_to_supplier 5550 5550 0 0.00 0 4345 8 0 0
t$perf-norm:sp_reserve_write_off 9854 9854 0 0.00 0 17070 11 0 0
t$perf-norm:sp_supplier_invoice 5398 5398 0 0.00 0 4591 17 0 0
t$perf-norm:sp_supplier_order 5191 5191 0 0.00 0 7580 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 658 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 48 1 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 3056 14 0 0
t$perf-norm:srv_make_money_saldo 674 674 0 0.00 0 2549 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 9712 9530 182 1.87 0 8195 19 0 182
v_all_customers 36127 36127 0 0.00 0 23 1 0 0
v_all_suppliers 29146 29146 0 0.00 0 22 1 0 0
v_all_wares 39970 39970 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 4037 13 0 0
v_cancel_client_order 3316 3316 0 0.00 0 2909 4 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 3230 11 0 0
v_cancel_customer_reserve 1242 1242 0 0.00 0 995 5 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 7220 15 0 0
v_cancel_supplier_invoice 818 800 18 2.20 0 1964 14 0 18
v_cancel_supplier_order 788 788 0 0.00 0 817 4 0 0
v_cancel_write_off 1956 1956 0 0.00 0 8014 23 0 0
v_max_id_clo_ord 5188 5188 0 0.00 0 226 1 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 6113 27 0 0
v_max_id_ord_sup 5383 5383 0 0.00 0 5342 6 0 0
v_max_non_paid_invoice 1548 1548 0 0.00 0 1870 3 0 0
v_max_non_paid_realizn 3825 3825 0 0.00 0 522 3 0 0
v_min_id_clo_ord 5188 5188 0 0.00 0 11990 9 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 3631 12 0 0
v_min_id_ord_sup 5383 5383 0 0.00 0 11042 13 0 0
v_min_non_paid_invoice 5550 5550 0 0.00 0 6347 14 0 0
v_min_non_paid_realizn 6923 6923 0 0.00 0 4986 14 0 0
v_our_firm 10038 10038 0 0.00 0 14 1 0 0
v_reserve_write_off 19708 19708 0 0.00 0 7096 8 0 0
x_lock_depdocs_on_canc_invoice 11014 11014 0 0.00 0 23970 117 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 3402 288 0 0
x_make_qstorno_1000_1200 5191 5191 0 0.00 2 38141 1399 0 0
x_make_qstorno_1200_2000 5398 5398 0 0.00 3 114633 3383 0 0
x_make_qstorno_2100_3300 444127 444127 0 0.00 0 96627 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-09-2021 04:38:41 to 23-09-2021 04:38:45.

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:09 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:11 24227 8192000000 9714879856 10011312128 793454096 8.830 100 81 162 0 820714704 7735952 19524656
02:09:39 18429 8192000000 9767543600 10075275264 840851392 9.308 100 85 170 0 873327776 12518656 19957728
02:11:00 11400 8192000000 9795021136 10115108864 867887936 9.579 100 82 164 0 905559040 18689344 18981760
02:12:20 12964 8192000000 9872181424 10204594176 916993216 10.066 100 79 158 1 977792064 43682592 17116256
02:13:35 6318 8192000000 9931816144 10276556800 956912528 10.459 100 84 169 1 1042854544 68110352 17831664
02:14:46 5104 8192000000 9973767696 10326511616 985838128 10.741 100 84 168 0 1090736720 87704656 17193936
02:15:54 2749 8192000000 9988382496 10352656384 1024577264 11.116 100 85 168 0 1103643968 62476464 16590240
02:16:59 1393 8192000000 10025667712 10391015424 1037630464 11.242 100 85 170 0 1147056816 92389808 17036544
02:18:01 1850 8192000000 10046348320 10416877568 1061185680 11.468 100 87 174 0 1158566128 78827776 18552672
02:19:04 12209 8192000000 10034167184 10408968192 1065497216 11.509 100 81 165 2 1148605440 66002480 17105744
02:20:17 5949 8192000000 9996683344 10385477632 1067450928 11.528 100 38 77 0 1103929040 28708480 7769632
02:21:25 5249 8192000000 10001110144 10394476544 1082244256 11.669 100 49 97 2 1118528080 26506976 9776848
02:22:31 15935 8192000000 10030489984 10414194688 1086515600 11.710 100 65 132 0 1130409024 29802384 14091040
02:23:47 6770 8192000000 10028593072 10425991168 1090298000 11.745 100 46 88 4 1148528320 49326560 8903760
02:24:54 7262 8192000000 10003961104 10415747072 1086708064 11.711 99 34 69 3 1113037232 17198496 9130672
02:26:02 60292 8192000000 10004229920 10394882048 1080573920 11.653 98 40 80 0 1111040896 21634032 8832944
02:28:03 3521 8192000000 9983882544 10367901696 1022838096 11.099 96 58 116 0 1064344592 27922592 13583904
02:29:08 7050 8192000000 9959487824 10350989312 1023591664 11.107 97 41 82 1 1055343472 23461280 8290528
02:30:17 45826 8192000000 9944882800 10346520576 994162448 10.822 95 30 58 2 1028231280 28156240 5912592
02:32:03 8494 8192000000 9936414176 10327801856 1010896896 10.984 98 41 82 2 1026460288 6482272 9081120
02:33:12 8915 8192000000 9965429408 10362064896 1037454960 11.240 99 39 78 0 1072807424 26694928 8657536
02:34:21 7398 8192000000 9987719520 10371596288 1064796928 11.502 100 36 72 1 1096525984 23289600 8439456
02:35:31 43823 8192000000 9997621552 10414804992 1070709040 11.559 100 17 34 0 1097791184 23705792 3376352
02:37:16 25029 8192000000 10016068112 10413699072 1097268256 11.812 100 66 132 1 1135150080 23733888 14147936
02:38:54 7053 8192000000 10108906272 10501828608 1121507776 12.041 100 85 170 3 1223622800 84556432 17558592
02:40:08 33204 8192000000 10090238336 10508476416 1129093600 12.113 100 35 69 0 1217108432 80677712 7337120
02:41:46 21963 8192000000 10146117360 10549583872 1144911152 12.262 100 50 100 1 1250726176 95303552 10511472
02:43:13 19058 8192000000 10188376384 10605314048 1154119552 12.348 100 49 98 0 1302646912 136830608 11696752
02:44:42 7403 8192000000 10242355584 10650025984 1157006432 12.375 100 60 119 1 1345291424 174660176 13624816
02:45:53 7029 8192000000 10254084368 10679562240 1167632128 12.475 100 57 114 0 1363877840 182437088 13808624
02:47:05 6759 8192000000 10285891968 10699890688 1179185184 12.583 100 65 126 2 1391418368 198399968 13833216
02:48:16 4040 8192000000 10356341936 10766499840 1191148816 12.694 100 75 150 0 1458107120 249587248 17371056
02:49:23 1335 8192000000 10394617616 10800873472 1168020944 12.478 100 92 183 0 1493672064 304103872 21547248
02:50:33 2073 8192000000 10397562144 10803585024 1153286304 12.340 99 85 172 1 1491629232 318084256 20258672
02:51:38 2273 8192000000 10342627168 10772684800 1159009424 12.394 100 81 163 1 1450125648 272167280 18948944
02:52:44 13582 8192000000 10333076080 10758594560 1136879776 12.186 99 44 86 0 1417629024 269617712 11131536
02:54:01 3712 8192000000 10315458992 10743308288 1147096304 12.282 100 54 106 0 1425881968 266365376 12420288
02:55:12 17587 8192000000 10247088656 10686574592 1153521600 12.343 100 26 52 1 1348238144 188161456 6555088
02:56:49 10816 8192000000 10251314704 10686156800 1138541680 12.202 100 50 100 1 1361281264 210828992 11910592
02:58:08 17546 8192000000 10277203904 10710548480 1114270416 11.973 97 40 80 0 1363991168 239721872 9998880
02:59:27 11018 8192000000 10177563648 10641276928 1117957936 12.008 99 22 44 1 1275806576 152032976 5815664
03:01:15 26431 8192000000 10131635040 10589560832 1079082832 11.639 96 21 42 0 1208196416 123903664 5209920
03:02:43 25524 8192000000 10128082336 10614394880 1094315296 11.784 99 20 40 0 1249397632 150084528 4997808
03:04:16 104472 8192000000 10164157024 10596712448 1103747632 11.873 99 39 77 1 1227887872 116158944 7981296
03:07:09 40946 8192000000 10145707904 10580766720 1100015152 11.838 100 26 51 0 1252619520 147318640 5285728
03:08:54 42745 8192000000 10044145120 10475954176 1096176480 11.801 98 19 37 0 1129223584 29383136 3663968
03:10:45 165629 8192000000 10096973840 10556502016 1110601456 11.938 99 11 22 0 1174977120 62101088 2274576
03:14:31 11670 8192000000 10112260880 10543869952 1117629968 12.005 99 31 62 1 1211399872 86547152 7222752
03:15:44 75555 8192000000 10100792592 10535927808 1126141136 12.085 99 28 56 1 1176572560 44092608 6338816
03:18:03 79260 8192000000 10134967632 10570326016 1121111168 12.037 98 25 50 1 1210054944 83606144 5337632
03:20:22 69500 8192000000 10081334912 10515181568 1118864496 12.016 98 10 20 0 1162883616 41987920 2031200
03:22:36 40921 8192000000 10106047648 10547404800 1122489536 12.051 98 26 52 0 1179787936 52128144 5170256
03:24:20 67455 8192000000 10072571648 10502545408 1119733408 12.024 98 20 40 1 1177916432 53740112 4442912
03:26:27 68182 8192000000 10098871856 10549895168 1133127456 12.151 99 23 46 0 1188941136 51428016 4385664
03:28:42 75737 8192000000 10103961776 10546896896 1135600944 12.174 99 26 51 2 1190982800 49482688 5899168
03:30:58 17363 8192000000 10079885152 10532651008 1145988448 12.272 100 17 34 0 1189486640 39626656 3871536
03:32:24 74936 8192000000 10121576640 10584584192 1130367888 12.125 98 10 20 0 1201235472 68775664 2091920
03:35:04 61862 8192000000 10079776160 10567278592 1133087952 12.150 99 7 14 0 1169569008 35313952 1167104
03:37:09 56159 8192000000 10160854528 10604875776 1131826128 12.139 98 27 54 2 1224136272 86422640 5887504
03:39:09 60995 8192000000 10121433312 10561069056 1080431952 11.652 94 25 50 0 1176072464 90273840 5366672
03:41:10 69803 8192000000 10070541824 10512203776 1067899552 11.532 94 8 16 0 1094166096 24660768 1605776
03:43:20 53585 8192000000 10091098976 10569084928 1054004032 11.399 93 25 48 1 1093355824 34646928 4704864
03:45:14 54291 8192000000 10104727808 10548871168 1116494176 11.994 98 30 59 2 1178011568 54830096 6687296
03:47:10 93974 8192000000 10073751504 10537771008 1105068816 11.886 97 14 28 0 1164759200 56954320 2736064
03:49:44 139534 8192000000 10071942096 10543300608 1128404016 12.106 99 11 22 0 1189184816 58127824 2652976
03:53:05 39589 8192000000 10072828832 10529996800 1117924880 12.007 99 16 32 0 1168341904 46837808 3579216
03:54:49 43259 8192000000 10094377504 10560774144 1122330832 12.049 99 18 33 0 1179670400 53986176 3353392
03:56:32 42247 8192000000 10109524864 10580938752 1104007616 11.876 98 12 24 0 1197951536 90877072 3066848
03:58:27 48143 8192000000 10054969808 10516234240 1073494544 11.585 97 13 27 1 1138511344 62298976 2717824
04:00:15 30994 8192000000 10055286976 10485014528 1098309808 11.822 99 22 44 1 1142038672 38559792 5169072
04:01:55 50521 8192000000 10128917904 10576154624 1131405488 12.135 100 28 56 1 1215508688 77869248 6233952
04:03:48 43823 8192000000 10104238096 10600153088 1111440944 11.946 98 18 36 1 1202904208 87248976 4214288
04:05:38 27549 8192000000 10085412976 10529112064 1136947824 12.187 100 36 72 0 1174942208 30378096 7616288
04:07:06 61008 8192000000 10092284688 10586316800 1125690160 12.081 98 22 44 1 1206334512 76226720 4417632
04:09:14 66243 8192000000 10089615072 10588053504 1087767200 11.721 95 21 42 1 1151712288 59813680 4131408
04:12:02 79804 8192000000 10134806288 10591375360 1126775136 12.091 99 13 26 0 1197748512 68223872 2749504
04:14:23 33934 8192000000 10079254256 10531008512 1116200496 11.991 98 8 16 0 1172724768 54931328 1592944
04:16:00 52158 8192000000 10121906320 10575757312 1137603216 12.193 99 25 50 0 1218642128 75277600 5761312
04:17:53 64192 8192000000 10102420208 10544709632 1103665296 11.872 96 29 58 3 1173353072 63499008 6188768
04:19:58 64930 8192000000 10118037584 10560491520 1094170960 11.782 95 26 52 0 1168709072 68913648 5624464
04:22:03 30404 8192000000 10094402720 10575462400 1123246624 12.058 99 12 24 1 1207778496 82071920 2459952
04:23:34 43535 8192000000 10144850288 10573795328 1132102896 12.141 99 45 89 0 1216813728 75487984 9222848
04:25:21 23146 8192000000 10077069392 10515927040 1085562288 11.700 96 35 70 0 1122749072 30327328 6859456
04:26:47 95333 8192000000 10064639168 10496299008 1089971792 11.742 97 26 52 0 1146039056 50495936 5571328
04:29:26 57547 8192000000 10081789792 10526330880 1112161104 11.953 99 10 20 0 1176404080 61829648 2413328
04:31:35 61581 8192000000 10067914144 10539950080 1117937104 12.007 99 8 16 0 1160689456 41058752 1693600
04:33:55 82687 8192000000 10110166960 10565365760 1116183728 11.991 98 14 28 1 1206200336 86956368 3060240
04:36:26 48438 8192000000 9005285648 9257680896 164282480 1.965 15 2 4 0 179304800 14724944 297376

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-09-2021 04:38:47 to 23-09-2021 04:39:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-09-2021 04:39:03 to 23-09-2021 04:39:05.

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          232967
MON$OLDEST_ACTIVE               232968
MON$OLDEST_SNAPSHOT             232968
MON$NEXT_TRANSACTION            232968
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                       2036384
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2613 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2613 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2613 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2613 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 ---
:65: 
:66: mscs-test-161.budzdorov.ru    Thu Sep 23 04:37:18 2021
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
--- end of diff output ---

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

23.09.21 04:39:06. 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-09-23 04:39:08 No severe PSQL-related problems occured
23.09.21 04:39:10. End of report.