Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.11.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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16816336 kB
MemAvailable 31583568 kB
Buffers 20 kB
Cached 14805728 kB
SwapCached 0 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-11-15 04:37:08
DTS_END                         2021-11-15 04:37:08.6750
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/53520
TRN_ID                          217904
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: 8678.04 26680 1041365
customer order: creation 151.16 451 18139
customer order: refuse 13.68 375 1641
order to supplier: creation 41.05 1493 4926
order to supplier: removal 3.28 139679 393
invoice (draft): creation 40.61 4095 4873
invoice (draft): removal 49.85 1071 5982
invoice accept: apply 37.88 22109 4545
invoice accept: cancel 45.00 959 5400
customer reserve: creation 3589.48 211 430737
customer reserve: removal 4441.48 81 532977
realization accept: apply 73.97 227 8876
realization accept: cancel 75.73 139 9088
payment from customer: creation 52.74 134 6329
payment from customer: removal 5.15 103 618
payment to supplier: creation 43.99 150 5279
payment to supplier: removal 2.99 82 359
service: total inventory turnovers 3.23 4313 387
service: total monetary turnovers 5.93 601 712
service: aggregate perf. data 0.83 15022 99
service: refresh index statistics 0.04 342297 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-11-2021 04:38:27 to 15-11-2021 04:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9129
TEST_TIME 2 5949
TEST_TIME 3 5402
TEST_TIME 4 5053
TEST_TIME 5 5108
TEST_TIME 6 4853
TEST_TIME 7 4811
TEST_TIME 8 4700
TEST_TIME 9 4660
TEST_TIME 10 4826
TEST_TIME 11 5297
TEST_TIME 12 5269
TEST_TIME 13 5923
TEST_TIME 14 6092
TEST_TIME 15 6239
TEST_TIME 16 6397
TEST_TIME 17 6635
TEST_TIME 18 6806
TEST_TIME 19 7181
TEST_TIME 20 7537
TEST_TIME 21 7927
TEST_TIME 22 7978
TEST_TIME 23 8358
TEST_TIME 24 8669
TEST_TIME 25 9172
TEST_TIME 26 9406
TEST_TIME 27 10045
TEST_TIME 28 11283
TEST_TIME 29 10952
TEST_TIME 30 10829
TEST_TIME 31 10874
TEST_TIME 32 10622
TEST_TIME 33 10479
TEST_TIME 34 10401
TEST_TIME 35 10223
TEST_TIME 36 10191
TEST_TIME 37 10040
TEST_TIME 38 10058
TEST_TIME 39 9845
TEST_TIME 40 9782
TEST_TIME 41 9697
TEST_TIME 42 9672
TEST_TIME 43 9529
TEST_TIME 44 9374
TEST_TIME 45 9393
TEST_TIME 46 9368
TEST_TIME 47 9432
TEST_TIME 48 9303
TEST_TIME 49 9270
TEST_TIME 50 9315
TEST_TIME 51 9298
TEST_TIME 52 9254
TEST_TIME 53 9217
TEST_TIME 54 9107
TEST_TIME 55 9277
TEST_TIME 56 9440
TEST_TIME 57 9393
TEST_TIME 58 9376
TEST_TIME 59 9410
TEST_TIME 60 9453
TEST_TIME 61 9440
TEST_TIME 62 9506
TEST_TIME 63 9453
TEST_TIME 64 9418
TEST_TIME 65 9346
TEST_TIME 66 9268
TEST_TIME 67 9262
TEST_TIME 68 9236
TEST_TIME 69 9175
TEST_TIME 70 9072
TEST_TIME 71 9003
TEST_TIME 72 8977
TEST_TIME 73 8911
TEST_TIME 74 8918
TEST_TIME 75 9045
TEST_TIME 76 9052
TEST_TIME 77 9056
TEST_TIME 78 9056
TEST_TIME 79 9100
TEST_TIME 80 9127
TEST_TIME 81 9120
TEST_TIME 82 9246
TEST_TIME 83 9198
TEST_TIME 84 9233
TEST_TIME 85 9284
TEST_TIME 86 9239
TEST_TIME 87 9225
TEST_TIME 88 9236
TEST_TIME 89 9159
TEST_TIME 90 9108
TEST_TIME 91 9053
TEST_TIME 92 9028
TEST_TIME 93 8964
TEST_TIME 94 8923
TEST_TIME 95 8954
TEST_TIME 96 8916
TEST_TIME 97 8857
TEST_TIME 98 8859
TEST_TIME 99 8882
TEST_TIME 100 8895
TEST_TIME 101 8920
TEST_TIME 102 8888
TEST_TIME 103 8873
TEST_TIME 104 8887
TEST_TIME 105 8918
TEST_TIME 106 8888
TEST_TIME 107 8932
TEST_TIME 108 8946
TEST_TIME 109 8939
TEST_TIME 110 8982
TEST_TIME 111 8963
TEST_TIME 112 8902
TEST_TIME 113 8870
TEST_TIME 114 8838
TEST_TIME 115 8788
TEST_TIME 116 8725
TEST_TIME 117 8709
TEST_TIME 118 8714
TEST_TIME 119 8748
TEST_TIME 120 8682

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-11-2021 04:38:30 to 15-11-2021 04:38:33.

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 1437419 1437419 0 0.00 0 26415 8 0 0
doc_list_biud 1437418 1437418 0 0.00 0 55352 37 0 0
sp_add_invoice_to_stock 4674 4545 129 2.75 42 241926 22110 0 129
sp_cancel_adding_invoice 5400 5400 0 0.00 4 60123 959 0 0
sp_cancel_client_order 1641 1641 0 0.00 4 14680 375 0 0
sp_cancel_customer_reserve 532977 532977 0 0.00 1 38064 81 0 0
sp_cancel_pay_from_customer 618 618 0 0.00 3 3204 104 0 0
sp_cancel_pay_to_supplier 359 359 0 0.00 2 2527 82 0 0
sp_cancel_supplier_invoice 5992 5982 10 0.16 1 55354 1072 0 10
sp_cancel_supplier_order 393 393 0 0.00 8 1337683 139679 0 0
sp_cancel_write_off 9088 9088 0 0.00 1 9978 139 0 0
sp_client_order 18139 18139 0 0.00 4 36300 451 0 0
sp_customer_reserve 430737 430737 0 0.00 0 55687 211 0 0
sp_fill_shopping_cart 27918 27918 0 0.00 1 33377 50 0 0
sp_fill_shopping_cart_clo_res 430732 430732 0 0.00 0 21508 20 0 0
sp_get_clo_for_invoice 4539 4539 0 0.00 0 52764 479 0 0
sp_kill_cost_storno 15465 15465 0 0.00 0 10973 74 0 0
sp_kill_qstorno_ret_qs2qd 1081417 1081417 0 0.00 0 55351 44 0 0
sp_kill_qty_storno 568896 568896 0 0.00 0 55352 93 0 0
sp_lock_selected_doc 18740 18740 0 0.00 0 8238 6 0 0
sp_make_cost_storno 25023 25023 0 0.00 0 26412 72 0 0
sp_multiply_rows_for_pdistr 25023 25023 0 0.00 0 8189 36 0 0
sp_multiply_rows_for_qdistr 18139 18139 0 0.00 0 33649 208 0 0
sp_pay_from_customer 6329 6329 0 0.00 4 21159 135 0 0
sp_pay_to_supplier 5279 5279 0 0.00 5 15432 150 0 0
sp_payment_common 11608 11608 0 0.00 1 9858 23 0 0
sp_qd_handle_on_cancel_clo 1641 1641 0 0.00 0 14674 340 0 0
sp_qd_handle_on_invoice_upd_sts 9939 9939 0 0.00 0 22025 423 0 0
sp_qd_handle_on_reserve_upd_sts 8876 8876 0 0.00 0 13013 71 0 0
sp_reserve_write_off 8876 8876 0 0.00 6 26423 227 0 0
sp_supplier_invoice 4873 4873 0 0.00 8 85669 4095 0 0
sp_supplier_order 4926 4926 0 0.00 5 37435 1494 0 0
srv_aggregate_perf_data 99 99 0 0.00 3107 141256 15023 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1023 184750 49495 0 0
srv_find_qd_qs_mism 996875 996875 0 0.00 0 17097 6 0 0
srv_make_invnt_saldo 387 387 0 0.00 56 104679 4314 0 0
srv_make_money_saldo 712 712 0 0.00 1 22841 602 0 0
srv_recalc_idx_stat 5 5 0 0.00 211480 538665 342298 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4545 4545 0 0.00 0 15020 188 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 0 2900 76 0 0
t$perf-norm:sp_cancel_client_order 1641 1641 0 0.00 0 3852 12 0 0
t$perf-norm:sp_cancel_customer_reserve 604 604 0 0.00 0 2325 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 618 618 0 0.00 0 16571 33 0 0
t$perf-norm:sp_cancel_pay_to_supplier 359 359 0 0.00 0 1419 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 659 22 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 34926 2001 0 0
t$perf-norm:sp_cancel_write_off 939 939 0 0.00 0 7657 16 0 0
t$perf-norm:sp_client_order 18139 18139 0 0.00 0 8382 20 0 0
t$perf-norm:sp_customer_reserve 895 895 0 0.00 0 3950 14 0 0
t$perf-norm:sp_pay_from_customer 6329 6329 0 0.00 0 13371 13 0 0
t$perf-norm:sp_pay_to_supplier 5279 5279 0 0.00 0 7191 10 0 0
t$perf-norm:sp_reserve_write_off 8876 8876 0 0.00 0 6204 10 0 0
t$perf-norm:sp_supplier_invoice 4873 4873 0 0.00 0 6455 21 0 0
t$perf-norm:sp_supplier_order 4926 4926 0 0.00 0 9978 23 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 681 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 127 4 0 0
t$perf-norm:srv_make_invnt_saldo 387 387 0 0.00 0 910 10 0 0
t$perf-norm:srv_make_money_saldo 712 712 0 0.00 0 2158 16 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 83 22 0 0
v_add_invoice_to_stock 9332 9074 258 2.76 0 4787 21 0 258
v_all_customers 33442 33442 0 0.00 0 38 1 0 0
v_all_suppliers 27514 27514 0 0.00 0 16 1 0 0
v_all_wares 36274 36274 0 0.00 0 101 1 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 1790 10 0 0
v_cancel_client_order 3282 3282 0 0.00 0 6892 9 0 0
v_cancel_customer_prepayment 1236 1236 0 0.00 0 3089 14 0 0
v_cancel_customer_reserve 1208 1208 0 0.00 0 4785 16 0 0
v_cancel_payment_to_supplier 718 718 0 0.00 0 1492 5 0 0
v_cancel_supplier_invoice 804 784 20 2.48 0 4119 23 0 20
v_cancel_supplier_order 786 786 0 0.00 0 2344 11 0 0
v_cancel_write_off 1878 1878 0 0.00 0 3655 16 0 0
v_max_id_clo_ord 4923 4923 0 0.00 0 633 1 0 0
v_max_id_clo_res 895 895 0 0.00 0 896 6 0 0
v_max_id_ord_sup 4857 4857 0 0.00 0 7762 7 0 0
v_max_non_paid_invoice 1302 1302 0 0.00 0 75 2 0 0
v_max_non_paid_realizn 3177 3177 0 0.00 0 5487 7 0 0
v_min_id_clo_ord 4923 4923 0 0.00 0 4999 8 0 0
v_min_id_clo_res 895 895 0 0.00 0 5040 17 0 0
v_min_id_ord_sup 4857 4857 0 0.00 0 5692 13 0 0
v_min_non_paid_invoice 5279 5279 0 0.00 0 9852 17 0 0
v_min_non_paid_realizn 6329 6329 0 0.00 0 7951 16 0 0
v_our_firm 9136 9136 0 0.00 0 34 1 0 0
v_reserve_write_off 17752 17752 0 0.00 0 4889 8 0 0
x_lock_depdocs_on_canc_invoice 10990 10990 0 0.00 0 18326 131 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 10142 378 0 0
x_make_qstorno_1000_1200 4926 4926 0 0.00 2 37423 1407 0 0
x_make_qstorno_1200_2000 4873 4873 0 0.00 4 83944 3901 0 0
x_make_qstorno_2100_3300 430737 430737 0 0.00 0 55684 189 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-11-2021 04:38:34 to 15-11-2021 04:38:38.

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:12 18 8192000000 8801262832 8813338624 5768432 0.070 3 1 2 0 5869600 65392 35776
02:08:13 26562 8192000000 9715631808 10009325568 792122032 8.816 100 86 172 0 820444336 7354592 20967712
02:09:43 28261 8192000000 9756085600 10062479360 834060304 9.240 100 92 184 0 868532240 12333120 22138816
02:11:22 22781 8192000000 9807415760 10124398592 861676640 9.517 100 85 169 1 907079280 25806384 19596256
02:12:52 10364 8192000000 9865522592 10198016000 914250800 10.039 100 80 160 1 974803120 42648832 17903488
02:14:07 8378 8192000000 9926915744 10266300416 956914512 10.459 100 81 160 0 1028974448 54825296 17234640
02:15:18 7108 8192000000 9976306832 10330394624 992767664 10.808 100 85 168 2 1087350368 77367424 17215280
02:16:28 3971 8192000000 10001587232 10360127488 1023014928 11.101 100 92 183 1 1114095520 73206944 17873648
02:17:34 1622 8192000000 10015899344 10380275712 1036203072 11.228 100 92 183 1 1131157632 76617424 18337136
02:18:38 1600 8192000000 10052375536 10428923904 1068746272 11.540 100 86 171 0 1165650208 79342048 17561888
02:19:41 9273 8192000000 10049450128 10424655872 1082918784 11.675 100 85 167 1 1162724144 63019872 16785488
02:20:51 4585 8192000000 10020485568 10420097024 1089612624 11.739 100 49 99 1 1137164016 38241568 9309824
02:21:56 7465 8192000000 10009152256 10413006848 1094159936 11.782 100 45 91 2 1129592384 26190576 9241872
02:23:04 7937 8192000000 10000496288 10400784384 1096079328 11.800 100 29 56 1 1120164144 18008528 6076288
02:24:13 36759 8192000000 10031221984 10423562240 1101767344 11.854 100 52 104 1 1134411264 20963600 11680320
02:25:50 6199 8192000000 10014010000 10420334592 1108331808 11.917 100 50 98 1 1137539952 19544096 9664048
02:26:58 8839 8192000000 10017776656 10426048512 1104392912 11.879 100 29 56 1 1136627744 26470560 5764272
02:28:08 7555 8192000000 10019523776 10426118144 1097735760 11.816 100 34 68 3 1126834432 21499904 7598768
02:29:18 41230 8192000000 10004627712 10412273664 1032411184 11.192 95 29 57 0 1059252368 20439552 6401632
02:31:03 10000 8192000000 9970322032 10371133440 1014798960 11.022 96 28 56 0 1056946800 36205776 5942064
02:32:14 6879 8192000000 9942529200 10323554304 1005234240 10.929 97 34 68 0 1031567248 18922464 7410544
02:33:24 5808 8192000000 9930231872 10308579328 995526384 10.835 98 51 103 4 1019363488 12188912 11648192
02:34:33 41818 8192000000 9980188464 10380226560 1047855952 11.340 100 34 69 0 1080285440 25220960 7208528
02:36:23 6738 8192000000 9969955136 10370273280 1061476480 11.471 100 49 98 0 1086157856 13579488 11101888
02:37:36 21908 8192000000 10017766192 10405441536 1083115408 11.677 100 55 110 1 1114749088 20060288 11573392
02:39:05 91885 8192000000 10048302848 10458746880 1099653488 11.834 100 34 68 0 1154355680 47542912 7159280
02:42:03 17303 8192000000 10103472240 10513895424 1120222640 12.029 100 50 98 2 1212025168 80743104 11059424
02:43:23 12169 8192000000 10153968896 10557091840 1133325456 12.153 100 57 114 0 1264719760 118959296 12435008
02:44:44 21576 8192000000 10190392896 10605178880 1145639120 12.269 100 50 99 1 1299516592 142822416 11055056
02:46:23 32704 8192000000 10235497104 10651549696 1155735520 12.363 100 30 60 2 1326851664 163512656 7603488
02:47:58 1022 8192000000 10342789792 10736214016 1177260448 12.565 100 91 184 0 1445060304 248058256 19741600
02:49:04 8521 8192000000 10379015856 10793312256 1189898336 12.682 100 67 134 0 1476406976 270978960 15529680
02:50:26 3709 8192000000 10368113296 10807934976 1194656960 12.727 100 70 137 0 1473262656 262839984 15765712
02:51:37 6649 8192000000 10418206160 10827554816 1202587360 12.800 100 72 140 1 1515973728 298101088 15285280
02:52:53 2221 8192000000 10477412864 10893340672 1219344384 12.956 100 79 157 0 1572513088 335755840 17412864
02:54:02 2752 8192000000 10505862560 10930593792 1196917552 12.748 100 83 165 0 1606886688 391365280 18603856
02:55:11 1232 8192000000 10543861264 10950901760 1198063056 12.758 100 93 185 1 1640582000 421470336 21048608
02:56:16 1713 8192000000 10557857776 10961252352 1194656304 12.727 99 91 181 2 1652558048 436405632 21496112
02:57:27 2165 8192000000 10543521040 10955526144 1192998624 12.711 100 73 146 0 1636663280 425907392 17757264
02:58:32 4328 8192000000 10536021168 10964692992 1170334912 12.500 100 57 113 0 1633978768 449285312 14358544
02:59:42 10860 8192000000 10571439536 10994491392 1157508448 12.380 99 44 88 2 1646396400 477405712 11482240
03:01:01 3616 8192000000 10546988816 10960007168 1166623200 12.465 100 77 156 1 1640535968 454212096 19700672
03:02:08 14091 8192000000 10494277008 10948886528 1164146384 12.442 99 40 80 1 1599952032 425627392 10178256
03:03:37 49104 8192000000 10405569472 10860277760 1103907536 11.875 98 23 46 0 1482662464 372279536 6475392
03:05:39 27628 8192000000 10088618400 10523226112 1125636336 12.080 100 9 18 0 1196313888 68586432 2091120
03:07:09 37229 8192000000 10100064144 10543013888 1108341696 11.917 98 41 82 1 1186772032 69528832 8901504
03:08:50 58280 8192000000 10073978000 10505953280 1101177712 11.849 98 15 30 1 1145031296 40280048 3573536
03:10:57 96672 8192000000 10080525776 10502635520 1090359072 11.746 97 27 56 0 1152649360 55681152 6609136
03:13:38 56596 8192000000 10059611360 10490839040 1087179504 11.716 97 28 56 2 1132540800 38487648 6873648
03:15:44 84806 8192000000 10066706848 10488356864 1104940832 11.884 98 61 120 2 1144669216 26701760 13026624
03:18:09 50298 8192000000 10098143920 10526937088 1118748016 12.015 99 41 79 2 1185300848 58634896 7917936
03:20:07 75461 8192000000 10103590448 10543935488 1124904448 12.073 99 19 38 2 1211610752 82726656 3979648
03:22:24 22701 8192000000 10166406736 10616659968 1135906864 12.177 99 53 105 0 1264145552 116976576 11262112
03:23:55 34503 8192000000 10100928256 10605158400 1137505648 12.192 100 13 26 1 1224523808 84341616 2676544
03:25:38 71021 8192000000 10088417760 10571542528 1135100960 12.169 100 12 24 0 1207771088 70253792 2416336
03:28:32 68261 8192000000 10066310816 10509328384 1109862576 11.931 97 13 26 0 1134257360 22138656 2256128
03:30:42 36948 8192000000 10126865776 10573029376 1133870448 12.158 99 33 66 0 1231526416 90914176 6741792
03:32:32 119016 8192000000 10069857888 10548682752 1119950944 12.027 98 5 10 0 1180257216 59483440 822832
03:35:32 39609 8192000000 10095606288 10552795136 1108114000 11.915 97 31 60 1 1158393488 44446624 5832864
03:37:13 87244 8192000000 10073193344 10539507712 1104403552 11.879 97 18 36 0 1194037264 85105680 4528032
03:39:47 62235 8192000000 10083946192 10533269504 1107018128 11.904 97 12 24 0 1154945792 45530096 2397568
03:41:52 61530 8192000000 10049133984 10505134080 1109225568 11.925 99 46 87 2 1148377360 30247632 8904160
03:44:08 48373 8192000000 10079878464 10549256192 1124714768 12.072 100 39 79 0 1180613616 47858544 8040304
03:46:45 61934 8192000000 10057386208 10503405568 1112268144 11.954 99 19 38 0 1153336400 36525680 4542576
03:48:50 151791 8192000000 10135394800 10642132992 1103728480 11.873 98 20 40 1 1221033088 112352784 4951824
03:52:22 44123 8192000000 10043235952 10481790976 1081113840 11.658 97 19 38 2 1138925296 53946912 3864544
03:54:10 42752 8192000000 10089662720 10536505344 1089787200 11.741 97 22 44 1 1147605136 53293136 4524800
03:55:57 82415 8192000000 10120494992 10632544256 1128366624 12.106 100 35 70 1 1233929760 98450496 7112640
03:58:33 95260 8192000000 10092405664 10584944640 1127703136 12.100 100 28 56 2 1216330976 82896576 5731264
04:01:08 40039 8192000000 10127364160 10598330368 1135214480 12.170 100 22 44 0 1224749376 84841056 4693840
04:03:06 34104 8192000000 10060044160 10490445824 1073442400 11.585 95 44 87 1 1093598304 11633104 8522800
04:04:45 38565 8192000000 10119727712 10543751168 1128319392 12.106 100 39 79 0 1203127024 67186512 7621120
04:06:28 106136 8192000000 10065754976 10503176192 1127906976 12.102 99 24 48 1 1188152192 55530288 4714928
04:09:18 68884 8192000000 10075365184 10535837696 1115140320 11.981 98 10 20 0 1176973904 59757232 2076352
04:11:27 61172 8192000000 10088397488 10554773504 1117655072 12.005 99 22 44 0 1196983440 74421296 4907072
04:13:40 74162 8192000000 10093847952 10552295424 1112139584 11.953 98 10 20 0 1186210464 72037840 2033040
04:16:01 100504 8192000000 10082008192 10564435968 1137488864 12.192 100 19 38 1 1189587120 48209664 3888592
04:19:15 25694 8192000000 10063770016 10503589888 1113426352 11.965 98 31 62 1 1145730512 26132432 6171728
04:20:42 58504 8192000000 10089402288 10564186112 1108080512 11.914 98 23 46 1 1174198864 61453920 4664432
04:22:43 50778 8192000000 10118077648 10554572800 1143611936 12.249 100 42 84 2 1196632976 44862592 8158448
04:24:45 45269 8192000000 10082024432 10559442944 1112604528 11.957 97 23 46 0 1176628960 59787136 4237296
04:26:38 43206 8192000000 10077512192 10534125568 1110503952 11.937 98 27 54 2 1166342112 50166464 5671696
04:28:26 184750 8192000000 10057218912 10513465344 1077367088 11.622 95 20 40 1 1127709504 45732304 4610112
04:33:03 99185 8192000000 10127473808 10602184704 1136028816 12.178 100 9 18 1 1196377552 58319760 2028976
04:36:10 61966 8192000000 9042403904 9301270528 198725456 2.368 18 2 4 0 210704128 11691616 287056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-11-2021 04:38:40 to 15-11-2021 04:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-11-2021 04:38:56 to 15-11-2021 04:38:58.

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          218178
MON$OLDEST_ACTIVE               218179
MON$OLDEST_SNAPSHOT             218179
MON$NEXT_TRANSACTION            218179
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                       2031072
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2660 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2660 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2660 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2660 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 ---
:81: 
:82: mscs-test-161.budzdorov.ru    Mon Nov 15 04:37:14 2021
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:84: 
--- end of diff output ---

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

15.11.21 04:38:59. 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-11-15 04:39:01 No severe PSQL-related problems occured
15.11.21 04:39:02. End of report.