Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.07.2022 07:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15906976 kB
MemAvailable 31111704 kB
Buffers 0 kB
Cached 15770212 kB
SwapCached 2540 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-07-07 07:37:17
DTS_END                         2022-07-07 07:37:17.5820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45236
TRN_ID                          222342
ATT_ID                          667
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: 9229.18 21590 1107502
customer order: creation 172.10 456 20652
customer order: refuse 14.86 400 1783
order to supplier: creation 45.05 1378 5406
order to supplier: removal 3.64 148656 437
invoice (draft): creation 45.19 2978 5423
invoice (draft): removal 51.47 930 6176
invoice accept: apply 41.78 25183 5014
invoice accept: cancel 46.80 1129 5616
customer reserve: creation 3935.09 237 472211
customer reserve: removal 4576.10 93 549132
realization accept: apply 84.73 269 10168
realization accept: cancel 84.87 149 10184
payment from customer: creation 58.08 170 6970
payment from customer: removal 5.81 103 697
payment to supplier: creation 47.78 202 5734
payment to supplier: removal 3.43 112 412
service: total inventory turnovers 4.28 3517 513
service: total monetary turnovers 7.17 334 860
service: aggregate perf. data 0.91 14914 109
service: refresh index statistics 0.04 230596 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-07-2022 07:38:03 to 07-07-2022 07:38:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5612
TEST_TIME 2 6010
TEST_TIME 3 6043
TEST_TIME 4 6647
TEST_TIME 5 6179
TEST_TIME 6 6270
TEST_TIME 7 5961
TEST_TIME 8 6165
TEST_TIME 9 6393
TEST_TIME 10 6388
TEST_TIME 11 6586
TEST_TIME 12 6590
TEST_TIME 13 6596
TEST_TIME 14 6874
TEST_TIME 15 7387
TEST_TIME 16 7389
TEST_TIME 17 7662
TEST_TIME 18 7799
TEST_TIME 19 7837
TEST_TIME 20 7918
TEST_TIME 21 8364
TEST_TIME 22 8495
TEST_TIME 23 8656
TEST_TIME 24 8962
TEST_TIME 25 9498
TEST_TIME 26 9463
TEST_TIME 27 9508
TEST_TIME 28 10155
TEST_TIME 29 10756
TEST_TIME 30 10886
TEST_TIME 31 10692
TEST_TIME 32 10635
TEST_TIME 33 10618
TEST_TIME 34 10862
TEST_TIME 35 10824
TEST_TIME 36 10786
TEST_TIME 37 10649
TEST_TIME 38 10603
TEST_TIME 39 10697
TEST_TIME 40 10576
TEST_TIME 41 10535
TEST_TIME 42 10418
TEST_TIME 43 10287
TEST_TIME 44 10293
TEST_TIME 45 10427
TEST_TIME 46 10338
TEST_TIME 47 10345
TEST_TIME 48 10503
TEST_TIME 49 10322
TEST_TIME 50 10174
TEST_TIME 51 10111
TEST_TIME 52 10106
TEST_TIME 53 9991
TEST_TIME 54 9949
TEST_TIME 55 9886
TEST_TIME 56 9758
TEST_TIME 57 9763
TEST_TIME 58 9749
TEST_TIME 59 9829
TEST_TIME 60 9821
TEST_TIME 61 9781
TEST_TIME 62 9722
TEST_TIME 63 9684
TEST_TIME 64 9701
TEST_TIME 65 9620
TEST_TIME 66 9640
TEST_TIME 67 9699
TEST_TIME 68 9700
TEST_TIME 69 9700
TEST_TIME 70 9667
TEST_TIME 71 9731
TEST_TIME 72 9707
TEST_TIME 73 9798
TEST_TIME 74 9756
TEST_TIME 75 9815
TEST_TIME 76 9798
TEST_TIME 77 9778
TEST_TIME 78 9762
TEST_TIME 79 9692
TEST_TIME 80 9719
TEST_TIME 81 9646
TEST_TIME 82 9590
TEST_TIME 83 9553
TEST_TIME 84 9561
TEST_TIME 85 9501
TEST_TIME 86 9510
TEST_TIME 87 9432
TEST_TIME 88 9374
TEST_TIME 89 9406
TEST_TIME 90 9396
TEST_TIME 91 9396
TEST_TIME 92 9423
TEST_TIME 93 9441
TEST_TIME 94 9432
TEST_TIME 95 9492
TEST_TIME 96 9473
TEST_TIME 97 9519
TEST_TIME 98 9585
TEST_TIME 99 9573
TEST_TIME 100 9539
TEST_TIME 101 9548
TEST_TIME 102 9548
TEST_TIME 103 9509
TEST_TIME 104 9446
TEST_TIME 105 9423
TEST_TIME 106 9415
TEST_TIME 107 9389
TEST_TIME 108 9342
TEST_TIME 109 9309
TEST_TIME 110 9313
TEST_TIME 111 9286
TEST_TIME 112 9275
TEST_TIME 113 9241
TEST_TIME 114 9305
TEST_TIME 115 9277
TEST_TIME 116 9280
TEST_TIME 117 9294
TEST_TIME 118 9313
TEST_TIME 119 9316
TEST_TIME 120 9256

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-07-2022 07:38:07 to 07-07-2022 07:38:10.

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 1541123 1541123 0 0.00 0 29916 13 0 0
doc_list_biud 1541122 1541122 0 0.00 0 29158 39 0 0
sp_add_invoice_to_stock 5233 5014 219 4.18 75 247620 25183 0 219
sp_cancel_adding_invoice 5616 5616 0 0.00 5 73405 1129 0 0
sp_cancel_client_order 1783 1783 0 0.00 5 10034 401 0 0
sp_cancel_customer_reserve 549132 549132 0 0.00 1 25253 93 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 5194 103 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 3532 113 0 0
sp_cancel_supplier_invoice 6196 6176 20 0.32 2 28838 930 0 20
sp_cancel_supplier_order 437 437 0 0.00 13 1441787 148657 0 0
sp_cancel_write_off 10184 10184 0 0.00 2 14426 149 0 0
sp_client_order 20652 20652 0 0.00 5 23417 456 0 0
sp_customer_reserve 472211 472211 0 0.00 0 94750 237 0 0
sp_fill_shopping_cart 31476 31476 0 0.00 1 9038 74 0 0
sp_fill_shopping_cart_clo_res 472198 472198 0 0.00 0 19200 18 0 0
sp_get_clo_for_invoice 4968 4968 0 0.00 0 27668 287 0 0
sp_kill_cost_storno 16909 16909 0 0.00 0 29901 74 0 0
sp_kill_qstorno_ret_qs2qd 1115061 1115061 0 0.00 0 28568 41 0 0
sp_kill_qty_storno 588463 588463 0 0.00 0 29158 94 0 0
sp_lock_selected_doc 20949 20949 0 0.00 0 5715 6 0 0
sp_make_cost_storno 27839 27839 0 0.00 0 14260 77 0 0
sp_multiply_rows_for_pdistr 27839 27839 0 0.00 0 7379 38 0 0
sp_multiply_rows_for_qdistr 20652 20652 0 0.00 0 10110 174 0 0
sp_pay_from_customer 6970 6970 0 0.00 5 11325 171 0 0
sp_pay_to_supplier 5734 5734 0 0.00 6 26055 202 0 0
sp_payment_common 12704 12704 0 0.00 1 10430 46 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 10026 325 0 0
sp_qd_handle_on_invoice_upd_sts 10583 10583 0 0.00 0 29158 480 0 0
sp_qd_handle_on_reserve_upd_sts 10168 10168 0 0.00 0 16232 65 0 0
sp_reserve_write_off 10168 10168 0 0.00 8 16446 269 0 0
sp_supplier_invoice 5423 5423 0 0.00 8 78286 2979 0 0
sp_supplier_order 5406 5406 0 0.00 6 24036 1379 0 0
srv_aggregate_perf_data 109 109 0 0.00 2464 153082 14915 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 653 115495 37420 0 0
srv_find_qd_qs_mism 1057894 1057894 0 0.00 0 24124 10 0 0
srv_make_invnt_saldo 513 513 0 0.00 44 78101 3517 0 0
srv_make_money_saldo 860 860 0 0.00 1 11683 334 0 0
srv_recalc_idx_stat 5 5 0 0.00 101535 364621 230596 0 0
t$perf-abend:sp_add_invoice_to_stock 219 219 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 20 20 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5014 5014 0 0.00 1 10533 818 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 1 8154 432 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 3250 26 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 3036 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 257 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 2879 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 2118 43 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 85161 5868 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 2848 22 0 0
t$perf-norm:sp_client_order 20652 20652 0 0.00 0 5998 33 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 2711 14 0 0
t$perf-norm:sp_pay_from_customer 6970 6970 0 0.00 0 8663 17 0 0
t$perf-norm:sp_pay_to_supplier 5734 5734 0 0.00 0 5896 18 0 0
t$perf-norm:sp_reserve_write_off 10168 10168 0 0.00 0 5997 23 0 0
t$perf-norm:sp_supplier_invoice 5423 5423 0 0.00 0 8485 26 0 0
t$perf-norm:sp_supplier_order 5406 5406 0 0.00 0 3543 27 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 367 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 402 9 0 0
t$perf-norm:srv_make_invnt_saldo 513 513 0 0.00 0 5802 21 0 0
t$perf-norm:srv_make_money_saldo 860 860 0 0.00 0 1483 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 47 17 0 0
v_add_invoice_to_stock 10372 9934 438 4.22 0 9458 30 0 438
v_all_customers 37788 37788 0 0.00 0 93 3 0 0
v_all_suppliers 30478 30478 0 0.00 0 191 4 0 0
v_all_wares 41304 41304 0 0.00 0 146 3 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 4035 18 0 0
v_cancel_client_order 3566 3566 0 0.00 0 4211 13 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 1367 10 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 1810 11 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 3472 17 0 0
v_cancel_supplier_invoice 872 832 40 4.58 0 3404 31 0 40
v_cancel_supplier_order 874 874 0 0.00 0 2883 12 0 0
v_cancel_write_off 1930 1930 0 0.00 0 3159 23 0 0
v_max_id_clo_ord 5406 5406 0 0.00 0 5434 6 0 0
v_max_id_clo_res 959 959 0 0.00 0 3778 16 0 0
v_max_id_ord_sup 5417 5417 0 0.00 0 4476 6 0 0
v_max_non_paid_invoice 1318 1318 0 0.00 0 196 7 0 0
v_max_non_paid_realizn 3617 3617 0 0.00 0 1475 7 0 0
v_min_id_clo_ord 5406 5406 0 0.00 0 5613 10 0 0
v_min_id_clo_res 959 959 0 0.00 0 744 8 0 0
v_min_id_ord_sup 5417 5417 0 0.00 0 3242 7 0 0
v_min_non_paid_invoice 5734 5734 0 0.00 0 10427 20 0 0
v_min_non_paid_realizn 6970 6970 0 0.00 0 6414 23 0 0
v_our_firm 10222 10222 0 0.00 0 55 4 0 0
v_reserve_write_off 20336 20336 0 0.00 0 11025 15 0 0
x_lock_depdocs_on_canc_invoice 11376 11376 0 0.00 0 19361 130 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 15628 351 0 0
x_make_qstorno_1000_1200 5406 5406 0 0.00 2 24015 1235 0 0
x_make_qstorno_1200_2000 5423 5423 0 0.00 4 78276 2770 0 0
x_make_qstorno_2100_3300 472211 472211 0 0.00 0 94740 208 0 0

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

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:21 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:22 22422 8192000000 9721477024 10021253120 803917680 8.936 100 90 180 0 832851200 7242112 21691408
05:09:54 20318 8192000000 9770044128 10079256576 844681952 9.347 100 89 178 0 878085120 12194528 21208640
05:11:20 12569 8192000000 9802548576 10125238272 869986112 9.600 100 81 160 0 913742528 25337472 18418944
05:12:39 8778 8192000000 9868500656 10202828800 920407040 10.100 100 80 154 0 976141056 38653600 17080416
05:13:53 4070 8192000000 9941216064 10283745280 964545488 10.533 100 88 174 1 1047806800 65147968 18113344
05:15:01 3179 8192000000 9962256864 10316361728 989215136 10.774 100 89 170 1 1080074896 72723520 18136240
05:16:06 1311 8192000000 10008269856 10365980672 1017870944 11.051 100 98 191 2 1124496576 87798368 18827264
05:17:10 6017 8192000000 10010583456 10379034624 1036980208 11.236 100 82 161 0 1129984960 76988272 16016480
05:18:18 960 8192000000 10027497184 10399408128 1053569120 11.395 100 94 183 1 1143083264 71056512 18457632
05:19:19 1090 8192000000 10042125408 10418786304 1065440960 11.509 100 96 183 3 1163222448 79919008 17862480
05:20:22 1054 8192000000 10056672544 10437644288 1074213472 11.592 100 92 175 0 1179081936 88278544 16589920
05:21:24 1309 8192000000 10051126928 10441306112 1083964080 11.685 100 80 155 0 1173852096 75111136 14776880
05:22:30 1329 8192000000 10031899520 10422988800 1087577168 11.720 100 77 153 1 1154978080 52134928 15265984
05:23:32 1086 8192000000 10063784816 10451435520 1093502592 11.776 100 97 188 3 1183914880 71877744 18534544
05:24:35 1106 8192000000 10072414976 10464866304 1097324000 11.812 100 92 178 2 1194074816 78444320 18306496
05:25:37 1045 8192000000 10038792592 10423046144 1088088176 11.724 99 96 186 2 1166500112 60132000 18279936
05:26:39 905 8192000000 10051968224 10438918144 1098951616 11.828 100 88 168 3 1174425888 58148320 17325952
05:27:41 2015 8192000000 10057724704 10447765504 1091694176 11.759 100 80 148 2 1186363296 80063136 14605984
05:28:45 1796 8192000000 10041070576 10430984192 1085309008 11.698 100 80 151 1 1159932416 59112704 15510704
05:29:47 888 8192000000 10029830768 10423463936 1083936608 11.685 100 90 169 3 1152297808 50262800 18098400
05:30:49 1047 8192000000 10018880112 10404507648 1062135600 11.477 99 78 147 3 1143702304 67114448 14452256
05:31:51 1191 8192000000 10010240112 10387046400 1049214128 11.353 99 96 180 1 1132279024 65519008 17545888
05:32:53 1339 8192000000 9966246624 10341306368 1032168976 11.189 99 77 141 1 1087518112 40818960 14530176
05:33:55 1041 8192000000 9954809584 10327359488 1029944336 11.168 100 87 161 0 1078672240 31815680 16912224
05:34:56 1376 8192000000 9985251888 10358194176 1025905872 11.129 99 83 154 4 1113273536 71215184 16152480
05:35:59 940 8192000000 10010628544 10387812352 1040027760 11.265 100 93 177 1 1134180960 75742096 18411104
05:37:00 1259 8192000000 10004349616 10378989568 1044506288 11.308 100 87 164 2 1126991376 65901136 16583952
05:38:07 10421 8192000000 9999394656 10377670656 1064023104 11.495 100 75 135 1 1120125312 43432240 12669968
05:39:22 1094 8192000000 10066776944 10447757312 1081989296 11.666 100 94 173 0 1181364496 82685872 16689328
05:40:32 4470 8192000000 10077378496 10467545088 1093254912 11.774 100 71 131 2 1195630368 87876304 14499152
05:41:39 1056 8192000000 10100932944 10489712640 1105270912 11.888 99 89 175 0 1222756880 98193008 19292960
05:42:43 10274 8192000000 10153189360 10555195392 1116453392 11.993 100 62 122 1 1272674768 143569728 12651648
05:44:04 10712 8192000000 10181687216 10582433792 1132262384 12.143 100 70 132 0 1296376400 149928096 14185920
05:45:19 2016 8192000000 10236003360 10632777728 1140314000 12.218 100 91 175 1 1346914640 186608720 19991920
05:46:24 1015 8192000000 10262181184 10663899136 1155641872 12.362 100 95 189 0 1374800448 198150560 21008016
05:47:30 1639 8192000000 10283793472 10688180224 1162735072 12.429 100 81 154 0 1402588816 222599808 17253936
05:48:36 1419 8192000000 10308993488 10709266432 1161791168 12.420 100 95 181 0 1423202016 241144320 20266528
05:49:43 656 8192000000 10338952768 10740396032 1171948592 12.515 100 96 182 2 1447463744 254881696 20633456
05:50:47 1976 8192000000 10347523440 10780307456 1182520784 12.614 100 80 158 1 1458762832 257821888 18420160
05:51:55 1639 8192000000 10366980160 10807685120 1188901232 12.673 100 93 179 1 1474727888 265361232 20465424
05:52:59 917 8192000000 10403464144 10810077184 1198557776 12.763 100 95 184 1 1511452656 291736832 21158048
05:54:02 1576 8192000000 10430212416 10845798400 1194477744 12.725 100 94 186 2 1537218048 320539776 22200528
05:55:06 1847 8192000000 10473752240 10896650240 1203023744 12.804 100 96 184 0 1578678160 354641840 21012576
05:56:10 1025 8192000000 10509823968 10923253760 1208044208 12.851 100 93 175 0 1617886480 389325488 20516784
05:57:12 1195 8192000000 10520205040 10945654784 1193402608 12.715 100 93 177 2 1622868752 408001504 21464640
05:58:16 647 8192000000 10512473232 10978009088 1197173920 12.750 100 88 174 1 1620466784 401826832 21466032
05:59:19 5074 8192000000 10482145264 10940559360 1188939152 12.673 100 63 124 0 1601532752 396880656 15712944
06:00:30 4637 8192000000 10489471072 10962604032 1192414112 12.706 100 53 104 0 1593992304 387689232 13888960
06:01:37 3594 8192000000 10476406608 10952347648 1180643408 12.596 99 63 124 1 1579655488 382849680 16162400
06:02:45 4841 8192000000 10418758672 10869534720 1174965312 12.543 100 46 89 0 1536961296 351047296 10948688
06:03:54 21186 8192000000 10416819824 10883579904 1155224592 12.359 100 37 74 1 1527108464 362616656 9267216
06:06:18 43626 8192000000 10159793856 10624950272 1112749488 11.958 97 30 60 0 1245801072 126646928 6404656
06:08:04 7182 8192000000 10138799056 10580697088 1137918496 12.196 99 57 103 2 1252884976 103722096 11244384
06:09:42 41801 8192000000 10187511840 10665267200 1136737424 12.185 99 23 46 1 1301564080 159522240 5304416
06:11:25 65821 8192000000 10086333376 10595115008 1118308816 12.011 98 15 29 0 1223465552 102320336 2836400
06:13:52 80298 8192000000 10100231040 10541850624 1110305872 11.935 98 29 56 0 1161829440 45778096 5745472
06:16:24 83672 8192000000 10068944272 10510512128 1108490048 11.918 97 8 16 0 1141423312 31402784 1530480
06:18:50 33971 8192000000 10069364768 10510864384 1116118704 11.990 98 27 50 1 1147841776 26371536 5351536
06:20:33 29790 8192000000 10113677552 10549387264 1116626352 11.995 99 27 54 0 1179569024 57178304 5764368
06:22:12 98697 8192000000 10111643568 10571100160 1126093488 12.085 99 11 22 0 1183512192 55282352 2136352
06:24:53 63833 8192000000 10093101552 10613534720 1117890560 12.007 98 11 22 1 1202353296 81877632 2585104
06:27:06 61221 8192000000 10094586640 10530050048 1123774064 12.063 99 34 62 1 1156547648 26071872 6701712
06:29:09 44086 8192000000 10083889408 10555011072 1111652416 11.948 98 30 60 0 1191812000 73743440 6416144
06:31:48 66940 8192000000 10138563152 10584760320 1129144720 12.113 98 20 40 0 1219841104 86299008 4397376
06:33:57 39982 8192000000 10218448816 10657488896 1127696576 12.100 98 41 80 0 1279332608 142754112 8881920
06:35:57 71827 8192000000 10106760944 10560360448 1131551104 12.136 99 15 30 0 1259666864 123981648 4134112
06:38:09 75624 8192000000 10106034640 10587090944 1131950704 12.140 99 9 18 0 1188862688 55048032 1863952
06:40:25 54147 8192000000 10071055936 10520719360 1136216240 12.180 100 11 22 0 1194799776 56401376 2182160
06:42:20 28269 8192000000 10130493440 10566393856 1129163072 12.113 98 43 84 0 1191718864 54651728 7904064
06:43:48 50591 8192000000 10099637392 10558050304 1105507024 11.890 97 25 50 1 1162645056 52240784 4897248
06:45:45 30663 8192000000 10097925856 10561400832 1117052816 11.999 98 31 61 1 1188553632 65069888 6430928
06:47:20 37221 8192000000 10134482176 10576433152 1135032320 12.169 99 36 68 0 1194780400 52666544 7081536
06:48:59 30116 8192000000 10099628592 10537439232 1099931712 11.837 96 21 42 1 1147627360 43616160 4079488
06:50:31 67010 8192000000 10111027008 10575331328 1120762816 12.034 98 22 42 0 1189753504 65007008 3983680
06:52:42 62704 8192000000 10094670848 10589773824 1121149968 12.038 98 13 24 0 1177239904 53124576 2965360
06:54:45 16269 8192000000 10100015408 10522161152 1096620320 11.806 97 56 107 1 1159878352 51697088 11560944
06:56:04 110238 8192000000 10067854416 10548834304 1112456400 11.956 98 23 46 0 1201804736 84501408 4846928
06:58:57 24015 8192000000 10081303504 10551005184 1118880208 12.016 99 24 48 0 1179289312 55768720 4640384
07:00:21 75136 8192000000 10079620464 10546253824 1095501424 11.795 97 20 40 0 1161245776 61190224 4554128
07:02:43 75777 8192000000 10095283168 10570153984 1094654640 11.787 96 18 36 0 1165601712 67091904 3855168
07:05:28 65936 8192000000 10112509008 10607124480 1110018384 11.933 98 19 36 1 1196759808 82725504 4015920
07:07:35 84771 8192000000 10128104064 10577403904 1118103088 12.009 99 17 34 0 1254745200 132884656 3757456
07:10:03 28511 8192000000 10095164672 10567811072 1111109152 11.943 98 34 67 0 1179252112 60411120 7731840
07:11:38 44788 8192000000 10110931312 10543239168 1111218032 11.944 99 34 66 0 1169160816 51293632 6649152
07:13:24 38137 8192000000 10120731744 10552352768 1130709504 12.128 99 40 78 0 1198576448 59938656 7928288
07:15:04 85525 8192000000 10083232224 10539020288 1097742592 11.816 96 28 56 0 1160766768 57669920 5354256
07:17:29 23117 8192000000 10096156320 10527395840 1111213408 11.944 98 53 103 0 1177966944 56755568 9997968
07:18:54 54772 8192000000 10083802384 10519040000 1116630992 11.995 98 39 78 0 1173349792 49049200 7669600
07:20:50 115493 8192000000 10071741872 10541543424 1102814640 11.864 98 38 76 0 1161633776 50446656 8372480
07:23:46 64988 8192000000 10092062848 10594639872 1121418608 12.040 99 12 24 0 1186198992 62294816 2485568
07:26:15 92533 8192000000 10098508768 10574659584 1129212688 12.114 99 24 48 2 1199093920 64586576 5294656
07:28:57 69264 8192000000 10110815136 10576334848 1109108000 11.924 97 26 51 1 1174433424 60005504 5319920
07:31:07 54093 8192000000 10072103056 10553085952 1111799600 11.949 98 17 34 0 1158702000 43417424 3484976
07:33:01 10523 8192000000 10104057712 10539565056 1127823008 12.101 99 41 78 2 1189906384 53253696 8829680
07:34:15 43542 8192000000 10107918432 10584862720 1111557792 11.947 98 50 94 0 1190921344 70304128 9059424
07:36:01 40300 8192000000 10082397104 10538053632 1115711248 11.986 98 23 46 0 1170098736 49463232 4924256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-07-2022 07:38:17 to 07-07-2022 07:38:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 219 v_add_invoice_to_stock 335544517
except 20 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-07-2022 07:38:34 to 07-07-2022 07:38:36.

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          220619
MON$OLDEST_ACTIVE               222473
MON$OLDEST_SNAPSHOT             222473
MON$NEXT_TRANSACTION            222473
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                       2058080
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

07.07.22 07:38:37. 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-07-07 07:38:39 No severe PSQL-related problems occured
07.07.22 07:38:41. End of report.