Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2022 07: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 15671668 kB
MemAvailable 30896528 kB
Buffers 0 kB
Cached 16011504 kB
SwapCached 2508 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.2
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 2022-08-12 07:37:35
DTS_END                         2022-08-12 07:37:19.5240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42146
TRN_ID                          216857
ATT_ID                          259
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: 9227.50 23417 1107300
customer order: creation 165.15 493 19818
customer order: refuse 13.81 381 1657
order to supplier: creation 44.14 1401 5297
order to supplier: removal 3.33 182708 400
invoice (draft): creation 44.33 3357 5320
invoice (draft): removal 53.41 1069 6409
invoice accept: apply 40.77 25966 4892
invoice accept: cancel 48.30 1131 5796
customer reserve: creation 3848.23 244 461787
customer reserve: removal 4676.33 99 561159
realization accept: apply 79.19 265 9503
realization accept: cancel 86.08 173 10330
payment from customer: creation 56.44 173 6773
payment from customer: removal 5.61 114 673
payment to supplier: creation 46.40 197 5568
payment to supplier: removal 3.46 108 415
service: total inventory turnovers 4.13 3837 495
service: total monetary turnovers 7.37 360 884
service: aggregate perf. data 0.99 12605 119
service: refresh index statistics 0.04 233654 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-08-2022 07:38:28 to 12-08-2022 07:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8254
TEST_TIME 2 6038
TEST_TIME 3 5439
TEST_TIME 4 5102
TEST_TIME 5 4991
TEST_TIME 6 4818
TEST_TIME 7 4762
TEST_TIME 8 5218
TEST_TIME 9 5960
TEST_TIME 10 6180
TEST_TIME 11 6268
TEST_TIME 12 6464
TEST_TIME 13 6469
TEST_TIME 14 6492
TEST_TIME 15 6531
TEST_TIME 16 6542
TEST_TIME 17 6705
TEST_TIME 18 6779
TEST_TIME 19 6824
TEST_TIME 20 6977
TEST_TIME 21 7013
TEST_TIME 22 7138
TEST_TIME 23 7340
TEST_TIME 24 7554
TEST_TIME 25 7824
TEST_TIME 26 8237
TEST_TIME 27 8511
TEST_TIME 28 8823
TEST_TIME 29 9840
TEST_TIME 30 10338
TEST_TIME 31 10362
TEST_TIME 32 11056
TEST_TIME 33 11614
TEST_TIME 34 11582
TEST_TIME 35 11402
TEST_TIME 36 11397
TEST_TIME 37 11179
TEST_TIME 38 11018
TEST_TIME 39 10958
TEST_TIME 40 10832
TEST_TIME 41 10802
TEST_TIME 42 10769
TEST_TIME 43 10614
TEST_TIME 44 10552
TEST_TIME 45 10450
TEST_TIME 46 10328
TEST_TIME 47 10522
TEST_TIME 48 10394
TEST_TIME 49 10286
TEST_TIME 50 10213
TEST_TIME 51 10116
TEST_TIME 52 10135
TEST_TIME 53 10137
TEST_TIME 54 10141
TEST_TIME 55 10104
TEST_TIME 56 9997
TEST_TIME 57 9994
TEST_TIME 58 10126
TEST_TIME 59 10034
TEST_TIME 60 10032
TEST_TIME 61 10085
TEST_TIME 62 10180
TEST_TIME 63 10135
TEST_TIME 64 10239
TEST_TIME 65 10192
TEST_TIME 66 10105
TEST_TIME 67 10091
TEST_TIME 68 10068
TEST_TIME 69 10026
TEST_TIME 70 9986
TEST_TIME 71 9954
TEST_TIME 72 9895
TEST_TIME 73 9819
TEST_TIME 74 9761
TEST_TIME 75 9767
TEST_TIME 76 9727
TEST_TIME 77 9769
TEST_TIME 78 9728
TEST_TIME 79 9652
TEST_TIME 80 9724
TEST_TIME 81 9701
TEST_TIME 82 9704
TEST_TIME 83 9639
TEST_TIME 84 9749
TEST_TIME 85 9775
TEST_TIME 86 9780
TEST_TIME 87 9773
TEST_TIME 88 9797
TEST_TIME 89 9732
TEST_TIME 90 9693
TEST_TIME 91 9630
TEST_TIME 92 9649
TEST_TIME 93 9605
TEST_TIME 94 9534
TEST_TIME 95 9543
TEST_TIME 96 9519
TEST_TIME 97 9491
TEST_TIME 98 9479
TEST_TIME 99 9502
TEST_TIME 100 9498
TEST_TIME 101 9498
TEST_TIME 102 9520
TEST_TIME 103 9464
TEST_TIME 104 9556
TEST_TIME 105 9546
TEST_TIME 106 9535
TEST_TIME 107 9543
TEST_TIME 108 9538
TEST_TIME 109 9468
TEST_TIME 110 9434
TEST_TIME 111 9447
TEST_TIME 112 9429
TEST_TIME 113 9373
TEST_TIME 114 9340
TEST_TIME 115 9300
TEST_TIME 116 9286
TEST_TIME 117 9276
TEST_TIME 118 9253
TEST_TIME 119 9307
TEST_TIME 120 9258

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-08-2022 07:38:32 to 12-08-2022 07:38:35.

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 1532295 1532295 0 0.00 0 40472 13 0 0
doc_list_biud 1532278 1532278 0 0.00 0 40938 42 0 0
sp_add_invoice_to_stock 5025 4892 133 2.64 49 199196 25966 0 133
sp_cancel_adding_invoice 5796 5796 0 0.00 4 68585 1131 0 0
sp_cancel_client_order 1657 1657 0 0.00 6 17880 381 0 0
sp_cancel_customer_reserve 561159 561159 0 0.00 1 20691 100 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 4 1963 115 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 4269 108 0 0
sp_cancel_supplier_invoice 6425 6409 16 0.24 3 40947 1070 0 16
sp_cancel_supplier_order 400 400 0 0.00 13 1455282 182709 0 0
sp_cancel_write_off 10330 10330 0 0.00 2 24733 173 0 0
sp_client_order 19818 19818 0 0.00 6 50439 493 0 0
sp_customer_reserve 461787 461787 0 0.00 0 47998 244 0 0
sp_fill_shopping_cart 30424 30424 0 0.00 1 15493 76 0 0
sp_fill_shopping_cart_clo_res 461752 461752 0 0.00 0 18676 21 0 0
sp_get_clo_for_invoice 4841 4841 0 0.00 0 19341 302 0 0
sp_kill_cost_storno 17214 17214 0 0.00 0 21202 78 0 0
sp_kill_qstorno_ret_qs2qd 1139457 1139457 0 0.00 0 40936 43 0 0
sp_kill_qty_storno 600091 600091 0 0.00 0 40937 99 0 0
sp_lock_selected_doc 19925 19925 0 0.00 0 10873 6 0 0
sp_make_cost_storno 26684 26684 0 0.00 0 25257 85 0 0
sp_multiply_rows_for_pdistr 26681 26681 0 0.00 0 13017 36 0 0
sp_multiply_rows_for_qdistr 19818 19818 0 0.00 0 14800 190 0 0
sp_pay_from_customer 6773 6773 0 0.00 5 15987 174 0 0
sp_pay_to_supplier 5568 5568 0 0.00 6 24214 197 0 0
sp_payment_common 12340 12340 0 0.00 2 12212 45 0 0
sp_qd_handle_on_cancel_clo 1657 1657 0 0.00 0 17851 313 0 0
sp_qd_handle_on_invoice_upd_sts 10634 10634 0 0.00 0 22307 510 0 0
sp_qd_handle_on_reserve_upd_sts 9502 9502 0 0.00 0 16287 59 0 0
sp_reserve_write_off 9503 9503 0 0.00 8 25352 266 0 0
sp_supplier_invoice 5320 5320 0 0.00 9 122834 3357 0 0
sp_supplier_order 5297 5297 0 0.00 8 40386 1401 0 0
srv_aggregate_perf_data 119 119 0 0.00 2146 202143 12606 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 741 126183 34589 0 0
srv_find_qd_qs_mism 1059487 1059487 0 0.00 0 40472 10 0 0
srv_make_invnt_saldo 495 495 0 0.00 46 96956 3837 0 0
srv_make_money_saldo 884 884 0 0.00 1 20586 360 0 0
srv_recalc_idx_stat 5 5 0 0.00 65268 398784 233654 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4896 4896 0 0.00 1 9795 839 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 1 7351 384 0 0
t$perf-norm:sp_cancel_client_order 1657 1657 0 0.00 0 16501 40 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 1195 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 864 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 1692 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 1848 35 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 94858 6636 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 1262 17 0 0
t$perf-norm:sp_client_order 19818 19818 0 0.00 0 19500 35 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 2011 18 0 0
t$perf-norm:sp_pay_from_customer 6773 6773 0 0.00 0 10939 19 0 0
t$perf-norm:sp_pay_to_supplier 5568 5568 0 0.00 0 4752 18 0 0
t$perf-norm:sp_reserve_write_off 9503 9503 0 0.00 0 16628 24 0 0
t$perf-norm:sp_supplier_invoice 5320 5320 0 0.00 0 7155 31 0 0
t$perf-norm:sp_supplier_order 5297 5297 0 0.00 0 5405 28 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 2418 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 98 5 0 0
t$perf-norm:srv_make_invnt_saldo 495 495 0 0.00 0 3951 27 0 0
t$perf-norm:srv_make_money_saldo 884 884 0 0.00 0 2742 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 13 181 107 0 0
v_add_invoice_to_stock 9938 9672 266 2.67 0 6408 25 0 266
v_all_customers 36652 36652 0 0.00 0 63 3 0 0
v_all_suppliers 29737 29737 0 0.00 0 346 4 0 0
v_all_wares 39633 39633 0 0.00 0 346 4 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 211 13 0 0
v_cancel_client_order 3314 3314 0 0.00 0 3764 12 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 1919 12 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 3860 11 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 2725 11 0 0
v_cancel_supplier_invoice 776 744 32 4.12 0 844 23 0 32
v_cancel_supplier_order 800 800 0 0.00 0 575 10 0 0
v_cancel_write_off 1924 1924 0 0.00 0 1013 23 0 0
v_max_id_clo_ord 5291 5291 0 0.00 0 147 4 0 0
v_max_id_clo_res 947 947 0 0.00 0 2879 14 0 0
v_max_id_ord_sup 5312 5312 0 0.00 0 4362 6 0 0
v_max_non_paid_invoice 1300 1300 0 0.00 0 412 8 0 0
v_max_non_paid_realizn 3412 3412 0 0.00 0 614 7 0 0
v_min_id_clo_ord 5291 5291 0 0.00 0 6201 9 0 0
v_min_id_clo_res 947 947 0 0.00 0 3240 16 0 0
v_min_id_ord_sup 5312 5312 0 0.00 0 6088 7 0 0
v_min_non_paid_invoice 5566 5566 0 0.00 0 12206 20 0 0
v_min_non_paid_realizn 6773 6773 0 0.00 0 11105 17 0 0
v_our_firm 9702 9702 0 0.00 0 59 4 0 0
v_reserve_write_off 19004 19004 0 0.00 0 3768 9 0 0
x_lock_depdocs_on_canc_invoice 11833 11833 0 0.00 0 17783 121 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 9834 379 0 0
x_make_qstorno_1000_1200 5297 5297 0 0.00 3 40357 1254 0 0
x_make_qstorno_1200_2000 5320 5320 0 0.00 4 122770 3145 0 0
x_make_qstorno_2100_3300 461786 461786 0 0.00 0 47986 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2022 07:38:36 to 12-08-2022 07:38:40.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:23 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:24 29855 8192000000 9718185840 10014408704 783910800 8.733 100 87 174 0 813761952 8005152 21846000
05:09:57 16599 8192000000 9753872864 10059710464 829594208 9.195 100 89 176 0 862399344 11671584 21133552
05:11:27 12972 8192000000 9784541392 10104242176 852771520 9.428 100 82 162 0 894591696 22491808 19328368
05:12:45 6911 8192000000 9854881568 10187067392 911550000 10.013 100 77 150 0 965069744 36900784 16618960
05:13:57 3161 8192000000 9923111792 10261471232 959634768 10.485 100 93 183 0 1033255712 54628544 18992400
05:15:04 1513 8192000000 9981190768 10334928896 992761936 10.808 100 88 171 0 1100349072 89710752 17876384
05:16:08 1846 8192000000 9975901856 10336514048 1015880288 11.032 100 93 176 1 1092219984 60286544 16053152
05:17:11 978 8192000000 9999688112 10368778240 1036684224 11.233 100 98 190 3 1122721920 66449024 19588672
05:18:13 1054 8192000000 10011792320 10385518592 1054587072 11.405 100 89 168 2 1136830400 65598960 16644368
05:19:15 1236 8192000000 10060450592 10436968448 1062460144 11.480 100 96 187 2 1174526912 92678144 19388624
05:20:18 1406 8192000000 10039806912 10429321216 1070168496 11.554 100 76 145 5 1165939168 80158224 15612448
05:21:20 13988 8192000000 10022197184 10417823744 1077248528 11.621 100 57 115 0 1146208288 57372560 11587200
05:22:37 987 8192000000 10058359520 10444636160 1084986160 11.695 100 97 183 0 1178189648 74533808 18669680
05:23:39 1202 8192000000 10060531856 10445688832 1088288160 11.726 100 96 183 0 1181419248 73182464 19948624
05:24:41 1004 8192000000 10076942976 10469568512 1091454096 11.756 100 96 179 0 1199736336 88738112 19544128
05:25:43 1177 8192000000 10072259664 10459820032 1092718768 11.769 100 94 180 3 1192741152 81687136 18335248
05:26:45 1224 8192000000 10069873280 10458869760 1096421968 11.804 100 92 177 4 1187082128 73482624 17177536
05:27:47 910 8192000000 10049405312 10436714496 1086868864 11.713 100 92 181 2 1168450464 63793792 17787808
05:28:51 1077 8192000000 10042086128 10426470400 1082496560 11.671 100 92 178 1 1161128048 60144016 18487472
05:29:53 1166 8192000000 10048749904 10432790528 1075981120 11.609 100 96 186 1 1167421296 73380032 18060144
05:30:54 1052 8192000000 10044422480 10424471552 1066021728 11.514 100 97 183 0 1166607792 81544736 19041328
05:31:57 983 8192000000 10041142544 10423771136 1056828000 11.426 99 91 173 2 1168160416 92592352 18740064
05:32:58 2544 8192000000 9993305152 10379075584 1045785056 11.320 100 67 133 0 1124112240 65004976 13322208
05:34:04 857 8192000000 9984331440 10358288384 1033527504 11.202 99 92 180 2 1105785616 51970304 20287808
05:35:06 1798 8192000000 9950581360 10330198016 1021164352 11.083 99 59 109 4 1071966368 38783360 12018656
05:36:09 1523 8192000000 9953460688 10322833408 1016294032 11.036 100 87 161 6 1075934880 42312416 17328432
05:37:11 938 8192000000 10010890848 10384125952 1054817216 11.407 100 91 178 2 1129387664 56622704 17947744
05:38:18 10039 8192000000 10004840224 10390011904 1066148064 11.515 100 75 134 1 1119519344 41189744 12181536
05:39:35 2454 8192000000 10044334416 10424483840 1085008480 11.695 100 82 153 0 1158063888 57961856 15093552
05:40:41 14482 8192000000 10057995152 10448998400 1090032832 11.743 100 67 134 1 1191392080 87345392 14013856
05:42:14 10817 8192000000 10139888352 10533756928 1112602736 11.957 100 63 117 0 1247794896 122056592 13135568
05:43:46 5688 8192000000 10178781776 10581168128 1125980544 12.083 100 59 114 0 1289800432 150758960 13060928
05:45:00 5049 8192000000 10254607136 10646581248 1140800608 12.223 100 87 164 1 1368850288 210566736 17482944
05:46:07 924 8192000000 10270224512 10682679296 1149164256 12.302 100 86 166 1 1384466224 217697024 17604944
05:47:10 2464 8192000000 10300321152 10704121856 1159409968 12.398 100 80 156 0 1421835632 245069232 17356432
05:48:15 916 8192000000 10329347472 10731044864 1177238464 12.564 100 91 174 1 1441892432 245231072 19422896
05:49:18 737 8192000000 10378095504 10793041920 1180701472 12.597 100 91 176 2 1484740800 284353968 19685360
05:50:23 2027 8192000000 10432731760 10839867392 1195357056 12.733 100 96 180 1 1542617232 325709200 21550976
05:51:29 1435 8192000000 10463679968 10867752960 1205386064 12.826 100 95 177 1 1572477152 346285008 20806080
05:52:35 1162 8192000000 10522515728 10930380800 1211777184 12.886 100 94 178 0 1631166272 398073664 21315424
05:53:39 1642 8192000000 10572223984 10996047872 1222520400 12.985 100 88 168 0 1679699872 436391872 20787600
05:54:45 1124 8192000000 10617201120 11037700096 1233950112 13.090 100 97 192 2 1720071328 462607120 23514096
05:55:49 1001 8192000000 10663991920 11084025856 1237263744 13.121 100 97 189 0 1767725056 507281664 23179648
05:56:53 1933 8192000000 10701898144 11132780544 1229982976 13.054 100 97 192 0 1802792048 549477232 23331840
05:57:58 824 8192000000 10718328096 11144925184 1210808608 12.877 99 92 180 1 1822281248 588574096 22898544
05:59:03 1941 8192000000 10794737440 11233886208 1224780496 13.006 100 96 187 1 1889161360 640783552 23597312
06:00:11 3238 8192000000 10786238928 11244822528 1217371392 12.937 100 74 148 1 1889261568 652594592 19295584
06:01:19 3275 8192000000 10775464496 11250507776 1206378016 12.836 100 65 128 2 1876648432 653325568 16944848
06:02:24 3031 8192000000 10793975120 11280957440 1219368864 12.956 100 72 142 0 1896511696 657731968 19410864
06:03:29 9363 8192000000 10736160528 11216842752 1202185520 12.797 100 54 108 1 1851143936 633736560 15221856
06:04:54 21467 8192000000 10699177840 11211358208 1191612416 12.698 100 42 84 0 1827223808 623707440 11903952
06:06:16 18701 8192000000 10547633520 11106074624 1177945776 12.571 100 30 60 1 1667879408 481646160 8287472
06:07:36 33766 8192000000 10400141088 10953445376 1153645296 12.344 100 21 42 0 1500800032 341411632 5743104
06:09:10 19013 8192000000 10257003616 10823053312 1152063072 12.329 100 36 72 2 1354908144 194388096 8456976
06:10:54 30723 8192000000 10094772288 10561171456 1153485488 12.342 100 16 30 0 1190100864 32917168 3698208
06:12:26 14609 8192000000 10078162576 10505232384 1116893728 11.998 99 55 106 2 1170965456 42654928 11416800
06:14:42 49093 8192000000 10130606912 10565091328 1142616720 12.240 100 35 67 0 1205308640 56105088 6586832
06:16:32 126183 8192000000 10132843056 10588532736 1123678752 12.062 98 27 54 0 1236813264 106662368 6472144
06:19:39 45496 8192000000 10084423360 10516893696 1134835728 12.167 100 37 69 1 1209427472 67553520 7038224
06:21:26 43575 8192000000 10083107888 10522021888 1112689984 11.958 97 22 39 1 1148514416 31583792 4240640
06:23:18 21180 8192000000 10165563888 10601488384 1151726800 12.326 100 63 121 0 1253506928 88850576 12929552
06:24:58 25846 8192000000 10087566992 10538840064 1135750896 12.176 100 31 57 1 1199174128 57559072 5864160
06:26:26 39254 8192000000 10073898880 10531680256 1101591920 11.853 97 9 16 1 1160084736 56763632 1729184
06:28:06 89363 8192000000 10100105968 10601607168 1127180928 12.095 100 23 46 1 1211411504 78321024 5909552
06:30:49 84544 8192000000 10056437120 10540773376 1110226512 11.935 98 10 18 0 1173530112 61539616 1763984
06:33:15 53719 8192000000 10117144352 10562801664 1136482560 12.182 100 34 68 0 1229644368 85891472 7270336
06:35:22 38537 8192000000 10087444208 10540109824 1119583216 12.023 98 23 44 0 1154218880 29684992 4950672
06:37:03 32147 8192000000 10147435184 10592575488 1135834752 12.176 100 30 60 0 1239148544 96411424 6902368
06:38:54 70978 8192000000 10080753664 10555846656 1128021024 12.103 99 22 44 0 1191729344 59709984 3998336
06:41:07 96473 8192000000 10118667776 10580451328 1140454992 12.220 99 23 46 0 1185191744 40320864 4415888
06:43:46 30195 8192000000 10191350016 10642837504 1158924016 12.393 100 50 100 1 1281247392 111850016 10473360
06:45:22 94566 8192000000 10115197856 10584473600 1125060496 12.075 98 28 56 0 1196968336 65520720 6387120
06:47:57 2567 8192000000 10088025424 10516090880 1142231600 12.237 100 71 130 1 1189019680 32895024 13893056
06:49:00 49339 8192000000 10099768528 10562621440 1140346096 12.219 99 8 16 1 1199537312 57568272 1622944
06:50:55 74708 8192000000 10139463216 10605649920 1141988368 12.234 99 19 38 0 1226481440 80338656 4154416
06:53:11 72777 8192000000 10081068688 10596143104 1093672240 11.778 96 12 22 0 1190236448 93384640 3179568
06:55:30 74825 8192000000 10106326624 10538463232 1098725760 11.826 97 21 42 0 1170702784 67575600 4401424
06:57:49 21456 8192000000 10070808624 10512793600 1106112128 11.896 98 23 44 1 1164904336 54338160 4454048
06:59:12 16661 8192000000 10091129280 10518044672 1124104544 12.066 100 44 89 0 1182802624 49816864 8881216
07:00:30 14182 8192000000 10141384480 10565632000 1138016656 12.197 100 56 110 0 1228682000 79200512 11464832
07:01:48 57265 8192000000 10097521376 10573123584 1125703408 12.081 99 25 50 1 1177497136 47157568 4636160
07:03:45 60401 8192000000 10089422288 10549637120 1093950368 11.780 97 16 32 0 1181828624 84639312 3238944
07:05:51 17021 8192000000 10090673072 10519302144 1110896064 11.941 99 59 114 2 1165948128 43117632 11934432
07:07:19 73029 8192000000 10103152016 10552209408 1100274976 11.840 98 32 64 0 1205123936 98405040 6443920
07:09:33 27836 8192000000 10045591888 10521907200 1116762240 11.996 99 15 30 0 1151865904 32554912 2548752
07:11:01 28603 8192000000 10062059264 10515128320 1132917424 12.149 100 7 14 0 1166484896 32096672 1470800
07:12:30 70650 8192000000 10078264544 10545745920 1125519440 12.079 99 13 26 0 1176850160 48919424 2411296
07:14:43 38151 8192000000 10074561744 10510000128 1062387360 11.479 94 22 39 0 1128798816 61872416 4539040
07:16:21 53925 8192000000 10077762528 10544480256 1091211280 11.754 97 35 69 0 1141309024 42394464 7703280
07:18:59 48637 8192000000 10082163104 10550124544 1090984944 11.752 96 24 46 1 1163385568 67550768 4849856
07:20:48 54048 8192000000 10105208144 10580197376 1131323520 12.134 100 36 72 0 1251374352 112342880 7707952
07:23:10 44716 8192000000 10117383584 10581282816 1116584400 11.995 98 33 66 0 1193764000 70286208 6893392
07:24:57 73173 8192000000 10104614800 10566676480 1102523840 11.862 97 33 63 0 1172478224 63770144 6184240
07:27:20 89057 8192000000 10078400640 10565459968 1141119840 12.226 100 27 52 2 1192396976 45491008 5786128
07:29:58 54633 8192000000 10064272896 10517975040 1113932368 11.970 97 23 43 0 1146637520 28073040 4632112
07:31:58 83441 8192000000 10120102240 10580602880 1144397120 12.257 100 20 41 0 1197513728 48658560 4458048
07:34:23 50607 8192000000 10108271920 10548346880 1123623408 12.061 98 17 34 0 1184616048 57185232 3807408
07:36:20 38805 8192000000 10093331312 10584289280 1115250240 11.982 97 30 60 0 1217381504 95494512 6636752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-08-2022 07:38:42 to 12-08-2022 07:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-08-2022 07:38:59 to 12-08-2022 07:39:01.

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          222163
MON$OLDEST_ACTIVE               222164
MON$OLDEST_SNAPSHOT             222164
MON$NEXT_TRANSACTION            222164
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                       2054944
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
--- end of diff output ---

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

12.08.22 07:39:02. 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
2022-08-12 07:39:04 No severe PSQL-related problems occured
12.08.22 07:39:06. End of report.