Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.05.2022 10: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 16428488 kB
MemAvailable 31445028 kB
Buffers 0 kB
Cached 15251876 kB
SwapCached 2600 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.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-05-11 10:38:12
DTS_END                         2022-05-11 10:37:17.7380
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/48438
TRN_ID                          214066
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: 8869.07 24234 1064288
customer order: creation 164.28 451 19713
customer order: refuse 13.52 382 1622
order to supplier: creation 43.10 1229 5172
order to supplier: removal 3.28 157785 394
invoice (draft): creation 43.80 3158 5256
invoice (draft): removal 50.17 938 6020
invoice accept: apply 39.36 25811 4723
invoice accept: cancel 45.37 1154 5444
customer reserve: creation 3709.13 243 445095
customer reserve: removal 4477.97 90 537356
realization accept: apply 78.97 281 9477
realization accept: cancel 78.63 152 9435
payment from customer: creation 55.87 190 6704
payment from customer: removal 5.01 101 601
payment to supplier: creation 45.69 181 5483
payment to supplier: removal 3.23 104 388
service: total inventory turnovers 3.90 3708 468
service: total monetary turnovers 6.95 322 834
service: aggregate perf. data 0.83 16043 99
service: refresh index statistics 0.03 272361 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-05-2022 10:38:49 to 11-05-2022 10:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8384
TEST_TIME 2 6720
TEST_TIME 3 6825
TEST_TIME 4 6228
TEST_TIME 5 5850
TEST_TIME 6 5884
TEST_TIME 7 5703
TEST_TIME 8 6228
TEST_TIME 9 6382
TEST_TIME 10 6402
TEST_TIME 11 6524
TEST_TIME 12 6665
TEST_TIME 13 6954
TEST_TIME 14 7013
TEST_TIME 15 7121
TEST_TIME 16 7409
TEST_TIME 17 7540
TEST_TIME 18 7610
TEST_TIME 19 8133
TEST_TIME 20 8259
TEST_TIME 21 8232
TEST_TIME 22 8398
TEST_TIME 23 8455
TEST_TIME 24 8637
TEST_TIME 25 8774
TEST_TIME 26 8984
TEST_TIME 27 9283
TEST_TIME 28 9935
TEST_TIME 29 10385
TEST_TIME 30 10585
TEST_TIME 31 10667
TEST_TIME 32 10661
TEST_TIME 33 10454
TEST_TIME 34 10555
TEST_TIME 35 10517
TEST_TIME 36 10472
TEST_TIME 37 10311
TEST_TIME 38 10139
TEST_TIME 39 10032
TEST_TIME 40 9922
TEST_TIME 41 9794
TEST_TIME 42 10130
TEST_TIME 43 10072
TEST_TIME 44 10032
TEST_TIME 45 10005
TEST_TIME 46 9958
TEST_TIME 47 10023
TEST_TIME 48 9986
TEST_TIME 49 10038
TEST_TIME 50 9919
TEST_TIME 51 9766
TEST_TIME 52 9644
TEST_TIME 53 9627
TEST_TIME 54 9523
TEST_TIME 55 9514
TEST_TIME 56 9495
TEST_TIME 57 9445
TEST_TIME 58 9509
TEST_TIME 59 9431
TEST_TIME 60 9367
TEST_TIME 61 9313
TEST_TIME 62 9255
TEST_TIME 63 9298
TEST_TIME 64 9375
TEST_TIME 65 9465
TEST_TIME 66 9501
TEST_TIME 67 9491
TEST_TIME 68 9502
TEST_TIME 69 9500
TEST_TIME 70 9582
TEST_TIME 71 9577
TEST_TIME 72 9572
TEST_TIME 73 9606
TEST_TIME 74 9606
TEST_TIME 75 9578
TEST_TIME 76 9556
TEST_TIME 77 9453
TEST_TIME 78 9457
TEST_TIME 79 9410
TEST_TIME 80 9385
TEST_TIME 81 9342
TEST_TIME 82 9278
TEST_TIME 83 9258
TEST_TIME 84 9242
TEST_TIME 85 9159
TEST_TIME 86 9093
TEST_TIME 87 9165
TEST_TIME 88 9193
TEST_TIME 89 9165
TEST_TIME 90 9232
TEST_TIME 91 9170
TEST_TIME 92 9175
TEST_TIME 93 9234
TEST_TIME 94 9223
TEST_TIME 95 9232
TEST_TIME 96 9295
TEST_TIME 97 9267
TEST_TIME 98 9279
TEST_TIME 99 9343
TEST_TIME 100 9391
TEST_TIME 101 9353
TEST_TIME 102 9280
TEST_TIME 103 9225
TEST_TIME 104 9192
TEST_TIME 105 9198
TEST_TIME 106 9159
TEST_TIME 107 9145
TEST_TIME 108 9115
TEST_TIME 109 9075
TEST_TIME 110 9071
TEST_TIME 111 9032
TEST_TIME 112 9009
TEST_TIME 113 8973
TEST_TIME 114 8948
TEST_TIME 115 8933
TEST_TIME 116 8919
TEST_TIME 117 8943
TEST_TIME 118 8918
TEST_TIME 119 8911
TEST_TIME 120 8892

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-05-2022 10:38:53 to 11-05-2022 10:38:56.

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 1474365 1474365 0 0.00 0 24936 13 0 0
doc_list_biud 1474349 1474349 0 0.00 0 34647 38 0 0
sp_add_invoice_to_stock 4878 4723 155 3.17 65 198543 25811 0 155
sp_cancel_adding_invoice 5444 5444 0 0.00 7 103216 1155 0 0
sp_cancel_client_order 1622 1622 0 0.00 6 7212 383 0 0
sp_cancel_customer_reserve 537356 537356 0 0.00 1 34659 91 0 0
sp_cancel_pay_from_customer 601 601 0 0.00 4 3238 102 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 1727 105 0 0
sp_cancel_supplier_invoice 6031 6020 11 0.18 3 22493 939 0 11
sp_cancel_supplier_order 394 394 0 0.00 8 1127168 157786 0 0
sp_cancel_write_off 9435 9435 0 0.00 2 18775 152 0 0
sp_client_order 19713 19713 0 0.00 6 32901 452 0 0
sp_customer_reserve 445095 445095 0 0.00 1 158254 243 0 0
sp_fill_shopping_cart 30127 30127 0 0.00 1 18046 75 0 0
sp_fill_shopping_cart_clo_res 445068 445068 0 0.00 0 17820 18 0 0
sp_get_clo_for_invoice 4684 4684 0 0.00 0 27650 308 0 0
sp_kill_cost_storno 15868 15868 0 0.00 0 20973 71 0 0
sp_kill_qstorno_ret_qs2qd 1090561 1090561 0 0.00 0 34631 40 0 0
sp_kill_qty_storno 574425 574425 0 0.00 0 34646 91 0 0
sp_lock_selected_doc 19527 19527 0 0.00 0 7049 6 0 0
sp_make_cost_storno 26347 26347 0 0.00 0 24926 86 0 0
sp_multiply_rows_for_pdistr 26343 26343 0 0.00 0 8257 37 0 0
sp_multiply_rows_for_qdistr 19713 19713 0 0.00 0 18532 155 0 0
sp_pay_from_customer 6704 6704 0 0.00 5 22556 190 0 0
sp_pay_to_supplier 5483 5483 0 0.00 6 13440 181 0 0
sp_payment_common 12187 12187 0 0.00 2 8902 46 0 0
sp_qd_handle_on_cancel_clo 1622 1622 0 0.00 1 7204 313 0 0
sp_qd_handle_on_invoice_upd_sts 10123 10123 0 0.00 0 21005 475 0 0
sp_qd_handle_on_reserve_upd_sts 9475 9475 0 0.00 0 16596 67 0 0
sp_reserve_write_off 9477 9477 0 0.00 8 19700 282 0 0
sp_supplier_invoice 5256 5256 0 0.00 8 122053 3159 0 0
sp_supplier_order 5172 5172 0 0.00 8 31428 1230 0 0
srv_aggregate_perf_data 99 99 0 0.00 2944 124753 16044 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 818 219346 40499 0 0
srv_find_qd_qs_mism 1017817 1017817 0 0.00 0 16126 9 0 0
srv_make_invnt_saldo 468 468 0 0.00 48 62452 3709 0 0
srv_make_money_saldo 834 834 0 0.00 1 10802 322 0 0
srv_recalc_idx_stat 4 4 0 0.00 98472 484811 272362 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4727 4727 0 0.00 1 17704 867 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 1 6221 435 0 0
t$perf-norm:sp_cancel_client_order 1622 1622 0 0.00 0 7083 33 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 1023 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 601 601 0 0.00 0 1156 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 993 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 2610 44 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 1 95965 6957 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 1262 18 0 0
t$perf-norm:sp_client_order 19713 19713 0 0.00 0 6776 32 0 0
t$perf-norm:sp_customer_reserve 946 946 0 0.00 0 853 11 0 0
t$perf-norm:sp_pay_from_customer 6704 6704 0 0.00 0 5915 16 0 0
t$perf-norm:sp_pay_to_supplier 5483 5483 0 0.00 0 3753 18 0 0
t$perf-norm:sp_reserve_write_off 9477 9477 0 0.00 0 14231 23 0 0
t$perf-norm:sp_supplier_invoice 5256 5256 0 0.00 0 2835 27 0 0
t$perf-norm:sp_supplier_order 5172 5172 0 0.00 0 4160 27 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 173 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 143 11 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 430 13 0 0
t$perf-norm:srv_make_money_saldo 834 834 0 0.00 0 528 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 160 51 0 0
v_add_invoice_to_stock 9658 9348 310 3.20 0 7531 26 0 310
v_all_customers 36038 36038 0 0.00 0 143 3 0 0
v_all_suppliers 29330 29330 0 0.00 0 143 4 0 0
v_all_wares 39412 39412 0 0.00 0 136 3 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 451 11 0 0
v_cancel_client_order 3244 3244 0 0.00 0 4262 12 0 0
v_cancel_customer_prepayment 1202 1202 0 0.00 0 423 9 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 1 3585 12 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 245 9 0 0
v_cancel_supplier_invoice 764 742 22 2.87 0 4596 31 0 22
v_cancel_supplier_order 788 788 0 0.00 0 3797 15 0 0
v_cancel_write_off 1930 1930 0 0.00 0 4229 26 0 0
v_max_id_clo_ord 5171 5171 0 0.00 0 131 4 0 0
v_max_id_clo_res 946 946 0 0.00 0 3373 11 0 0
v_max_id_ord_sup 5246 5246 0 0.00 0 8387 11 0 0
v_max_non_paid_invoice 1234 1234 0 0.00 0 132 7 0 0
v_max_non_paid_realizn 3446 3446 0 0.00 0 454 6 0 0
v_min_id_clo_ord 5170 5170 0 0.00 0 3119 6 0 0
v_min_id_clo_res 946 946 0 0.00 0 6688 22 0 0
v_min_id_ord_sup 5246 5246 0 0.00 0 3077 7 0 0
v_min_non_paid_invoice 5483 5483 0 0.00 0 6594 16 0 0
v_min_non_paid_realizn 6704 6704 0 0.00 0 7597 27 0 0
v_our_firm 9890 9890 0 0.00 0 117 4 0 0
v_reserve_write_off 18950 18950 0 0.00 0 4517 10 0 0
x_lock_depdocs_on_canc_invoice 11093 11093 0 0.00 0 23336 141 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 9178 357 0 0
x_make_qstorno_1000_1200 5172 5172 0 0.00 2 31412 1083 0 0
x_make_qstorno_1200_2000 5256 5256 0 0.00 4 104003 2951 0 0
x_make_qstorno_2100_3300 445095 445095 0 0.00 0 158240 215 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-05-2022 10:38:57 to 11-05-2022 10:39:01.

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
08:07:21 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:22 21259 8192000000 9716508432 10016145408 799358832 8.890 100 95 190 0 830957408 7771648 23826928
08:09:46 26352 8192000000 9754953680 10064379904 835397216 9.254 100 85 170 1 867546544 11726864 20422464
08:11:15 17625 8192000000 9799875312 10118496256 865746768 9.558 100 82 164 1 904081104 19570144 18764192
08:12:37 9512 8192000000 9852590608 10183016448 904263568 9.941 100 79 158 0 961334240 39258368 17812304
08:13:51 9907 8192000000 9906524800 10248015872 943310768 10.325 100 85 169 0 1026494336 65117728 18065840
08:15:04 3593 8192000000 9953510192 10307424256 982060928 10.704 100 82 164 0 1069160064 70526416 16572720
08:16:10 1736 8192000000 9988719216 10342924288 1010267664 10.978 100 92 181 1 1101134464 72434304 18432496
08:17:14 909 8192000000 10026068928 10399244288 1032156368 11.189 100 96 181 1 1148212976 97879328 18177280
08:18:17 1320 8192000000 10024596112 10399514624 1048160240 11.343 100 84 166 1 1140553856 75072800 17320816
08:19:20 1437 8192000000 10034464848 10410229760 1056090976 11.419 100 96 178 0 1159057488 86577808 16388704
08:20:22 1339 8192000000 10046288016 10422624256 1065662448 11.511 100 98 187 2 1168579600 83632096 19285056
08:21:25 975 8192000000 10025696416 10407432192 1069340672 11.546 100 92 181 1 1148036832 60286528 18409632
08:22:28 1139 8192000000 10040947936 10423906304 1076121952 11.611 100 91 176 2 1163932208 69391824 18418432
08:23:30 1194 8192000000 10038345120 10424565760 1079819632 11.646 100 86 169 0 1159760496 62459968 17480896
08:24:32 1362 8192000000 10088515712 10473037824 1088232352 11.726 100 100 198 1 1209956208 101038400 20685456
08:25:35 1328 8192000000 10065943520 10449113088 1090459904 11.747 100 96 187 2 1181418096 71610464 19347728
08:26:38 2208 8192000000 10042814560 10436108288 1091965984 11.761 100 66 129 0 1174776448 70459968 12350496
08:27:41 1085 8192000000 10064204784 10455052288 1092852272 11.770 100 86 168 3 1184318592 72998400 18467920
08:28:44 4028 8192000000 10020162416 10410029056 1080906752 11.656 99 77 134 4 1143799536 48523680 14369104
08:29:49 1004 8192000000 10062304752 10449031168 1083102944 11.677 100 88 173 0 1184112064 83288256 17720864
08:30:51 1212 8192000000 10047021728 10431836160 1069255344 11.545 100 98 191 0 1169734720 81097264 19382112
08:31:54 1212 8192000000 10029416928 10408452096 1076331120 11.612 100 93 175 2 1150481744 56546000 17604624
08:32:56 1134 8192000000 10001229600 10377621504 1051058944 11.371 100 92 181 3 1121655056 52907872 17688240
08:33:57 1184 8192000000 9988702464 10355273728 1021577040 11.087 99 94 170 1 1113538608 73335088 18626480
08:34:59 1048 8192000000 10019231568 10395049984 1047136080 11.333 100 94 179 2 1140059216 73383808 19539328
08:36:01 3620 8192000000 10001269760 10381639680 1039208592 11.257 99 69 132 1 1108727824 55827472 13691760
08:37:06 6947 8192000000 9985032832 10359087104 1043823728 11.301 100 76 142 2 1108352960 50594288 13934944
08:38:21 6787 8192000000 10034307408 10419859456 1070170672 11.554 100 70 137 0 1155631200 72078704 13381824
08:39:33 2703 8192000000 10044934240 10425991168 1074940512 11.599 99 81 153 1 1170610544 80871744 14798288
08:40:40 22328 8192000000 10080928032 10470137856 1100869648 11.846 100 91 174 2 1195109536 77255136 16984752
08:42:08 2527 8192000000 10100295664 10488258560 1104307344 11.878 100 78 147 0 1217908928 97000288 16601296
08:43:13 7199 8192000000 10126009904 10534092800 1123615568 12.061 100 62 123 0 1246857408 109921568 13320272
08:44:26 1946 8192000000 10171061136 10561486848 1120452144 12.031 99 88 166 1 1283567136 145036032 18078960
08:45:32 1495 8192000000 10229907072 10623602688 1137469328 12.192 100 93 179 0 1343555056 185161872 20923856
08:46:38 1639 8192000000 10251230736 10651836416 1151150112 12.320 100 85 166 0 1362009104 192984208 17874784
08:47:44 932 8192000000 10265331392 10661978112 1153091744 12.339 100 87 167 0 1378608384 207165952 18350688
08:48:49 1604 8192000000 10305669232 10706845696 1167266960 12.471 100 90 167 3 1416238128 228893680 20077488
08:49:54 1112 8192000000 10317773552 10729570304 1179945344 12.590 100 98 194 2 1428069984 225566256 22558384
08:50:58 808 8192000000 10343937728 10764472320 1183064848 12.619 100 90 178 0 1453108416 249062768 20980800
08:52:02 1380 8192000000 10404286128 10822680576 1205414208 12.827 100 94 187 0 1508980704 282047072 21519424
08:53:05 1500 8192000000 10429190096 10861858816 1202708624 12.801 100 95 191 0 1530372368 305625328 22038416
08:54:10 877 8192000000 10471952592 10894229504 1207055600 12.842 100 98 196 1 1579860496 349978704 22826192
08:55:15 1326 8192000000 10485883232 10938572800 1209503744 12.865 100 80 160 0 1595599600 366358416 19737440
08:56:19 1318 8192000000 10481185408 10949296128 1203969776 12.813 100 97 192 2 1585077584 358060656 23047152
08:57:22 12326 8192000000 10482361888 10927063040 1204031024 12.814 100 60 116 0 1590773136 372202560 14539552
08:58:43 8289 8192000000 10478519824 10940137472 1195327696 12.733 100 59 112 0 1583777456 373114592 15335168
08:59:58 15250 8192000000 10507336768 10957320192 1195956144 12.739 100 46 89 0 1614660224 406850032 11854048
09:01:22 7087 8192000000 10490304496 10940362752 1178032880 12.572 99 41 82 0 1595738256 407009424 10695952
09:02:31 7212 8192000000 10471878880 10949525504 1172130160 12.517 100 48 90 1 1577247568 393445552 11671856
09:03:40 15533 8192000000 10404267872 10890973184 1144585264 12.259 99 33 66 2 1502013088 348854256 8573568
09:04:59 18540 8192000000 10301474640 10795335680 1142693552 12.241 100 29 56 3 1453390560 302701040 7995968
09:06:20 20806 8192000000 10222296960 10682449920 1131282128 12.133 98 28 56 0 1312275856 174109968 6883760
09:08:04 27450 8192000000 10205538688 10686869504 1139447584 12.210 99 27 54 2 1294817488 147896496 7473408
09:09:58 44007 8192000000 10168719648 10618179584 1131801744 12.138 100 29 53 3 1268005568 130728032 5475792
09:11:59 40113 8192000000 10110484560 10615951360 1144286448 12.256 100 22 44 1 1231759360 82774576 4698336
09:13:46 83149 8192000000 10108267872 10546896896 1139751184 12.213 99 36 72 0 1182588448 32994208 9843056
09:16:18 84191 8192000000 10145017632 10589270016 1076309968 11.612 94 33 65 2 1160994000 77249072 7434960
09:18:47 87053 8192000000 10083001536 10581340160 1106714256 11.901 99 8 16 0 1188186912 79701808 1770848
09:21:23 88148 8192000000 10068564656 10529783808 1061589168 11.472 95 15 29 1 1109929424 45179632 3160624
09:23:52 25361 8192000000 10074359568 10558914560 1115489728 11.984 100 34 66 2 1171488544 49371536 6627280
09:25:18 105410 8192000000 10060361840 10487795712 1131474832 12.135 100 25 42 2 1171602304 36254224 3873248
09:28:05 120913 8192000000 10134622256 10578006016 1129376512 12.115 100 29 58 1 1213482512 77543632 6562368
09:31:33 89301 8192000000 10121815824 10576244736 1113013184 11.961 98 44 86 1 1188345136 66064912 9267040
09:34:15 18535 8192000000 10116563712 10560778240 1130005328 12.121 100 18 35 2 1209280368 75588320 3686720
09:35:35 219346 8192000000 10145452512 10603843584 1082463744 11.671 95 22 44 0 1144532128 57529312 4539072
09:41:36 47706 8192000000 10093102080 10572537856 1136571840 12.183 99 16 32 0 1220124384 80388656 3163888
09:43:26 41741 8192000000 10086726992 10523824128 1150239136 12.312 100 26 45 1 1206902912 51858992 4804784
09:45:08 21914 8192000000 10116081424 10544680960 1121733728 12.043 98 47 95 2 1197986448 65733280 10519440
09:46:30 45647 8192000000 10153416784 10604548096 1137621360 12.193 100 31 62 2 1246481312 102035488 6824464
09:48:18 24452 8192000000 10104549312 10538520576 1136314992 12.181 99 33 61 4 1210561344 67538080 6708272
09:49:44 24809 8192000000 10103715168 10534162432 1096101136 11.801 97 54 102 1 1166352608 59576784 10674688
09:51:11 10802 8192000000 10098078416 10516463616 1114459408 11.975 99 46 92 2 1182153264 58979440 8714416
09:52:29 20898 8192000000 10078095184 10502008832 1097302256 11.812 98 44 83 0 1147271552 41770688 8198608
09:53:55 127211 8192000000 10067547408 10538438656 1106673312 11.901 99 34 68 1 1196867888 82580656 7613920
09:57:13 37919 8192000000 10053023056 10514120704 1113610912 11.967 99 16 32 1 1154463168 37204576 3647680
09:58:57 65547 8192000000 10120320544 10581225472 1128917696 12.111 100 19 38 0 1188456016 55476320 4062000
10:01:03 63609 8192000000 10118344832 10612879360 1139109840 12.207 100 16 32 0 1213266720 70404608 3752272
10:03:30 116582 8192000000 10062579440 10572009472 1126776432 12.091 99 9 18 1 1212137584 83361520 1999632
10:06:29 67569 8192000000 10134297296 10587021312 1082951792 11.676 95 12 24 1 1154679168 69312576 2414800
10:08:37 37890 8192000000 10127748000 10557771776 1144530768 12.258 100 41 78 2 1238149680 85179952 8438960
10:10:17 52231 8192000000 10096527072 10585624576 1089002560 11.733 96 20 40 3 1183050160 90239360 3808240
10:12:23 25650 8192000000 10124652432 10552397824 1123168400 12.057 100 36 70 0 1198407664 68016768 7222496
10:13:49 34715 8192000000 10121744016 10543239168 1099407664 11.832 97 44 88 1 1164452192 56362368 8682160
10:15:27 23852 8192000000 10138300176 10582315008 1128207024 12.104 100 50 100 0 1234246960 95708576 10331360
10:16:56 97476 8192000000 10067205680 10518724608 1102630480 11.863 97 17 34 0 1172554160 66560752 3362928
10:19:35 63562 8192000000 10077815312 10537455616 1124805984 12.072 99 46 90 0 1186274608 51621856 9846768
10:22:39 68722 8192000000 10078202752 10561642496 1127968320 12.102 99 9 18 0 1160308096 30431520 1908256
10:24:49 70330 8192000000 10053580496 10510295040 1121961824 12.046 98 17 33 1 1161704480 35612016 4130640
10:27:12 87794 8192000000 10079368000 10533789696 1114930064 11.979 98 21 42 0 1150544272 31095152 4519056
10:29:46 80267 8192000000 10075488768 10537320448 1127718592 12.100 99 20 40 0 1165850416 33442048 4689776
10:32:06 62304 8192000000 10093955728 10561163264 1124332064 12.068 98 20 40 1 1187887248 58979600 4575584
10:34:12 33391 8192000000 10145995456 10591232000 1145800768 12.270 99 42 82 1 1250256064 94928144 9527152
10:35:50 22648 8192000000 10206382752 10656059392 1164565472 12.446 100 46 93 1 1305232736 130047792 10619472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-05-2022 10:39:03 to 11-05-2022 10:39:19.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-05-2022 10:39:20 to 11-05-2022 10:39:22.

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          218020
MON$OLDEST_ACTIVE               218021
MON$OLDEST_SNAPSHOT             218021
MON$NEXT_TRANSACTION            218021
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                       2045408
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             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

11.05.22 10:39:23. 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-05-11 10:39:25 No severe PSQL-related problems occured
11.05.22 10:39:27. End of report.