Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.08.2021 13: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 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16228284 kB
MemAvailable 30992324 kB
Buffers 0 kB
Cached 15427592 kB
SwapCached 2616 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-13 13:37:06
DTS_END                         2021-08-13 13:37:06.6870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59676
TRN_ID                          222020
ATT_ID                          623
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: 8713.30 21031 1045596
customer order: creation 158.90 462 19068
customer order: refuse 13.86 372 1663
order to supplier: creation 43.08 1592 5170
order to supplier: removal 3.25 123243 390
invoice (draft): creation 42.07 3724 5048
invoice (draft): removal 48.42 1051 5810
invoice accept: apply 39.64 20785 4757
invoice accept: cancel 43.77 907 5252
customer reserve: creation 3689.52 202 442742
customer reserve: removal 4365.03 71 523804
realization accept: apply 77.29 208 9275
realization accept: cancel 72.36 126 8683
payment from customer: creation 52.96 147 6355
payment from customer: removal 5.41 129 649
payment to supplier: creation 44.93 136 5392
payment to supplier: removal 3.38 77 406
service: total inventory turnovers 3.18 4589 382
service: total monetary turnovers 5.34 792 641
service: aggregate perf. data 0.88 16009 105
service: refresh index statistics 0.03 246000 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-08-2021 13:38:16 to 13-08-2021 13:38:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9177
TEST_TIME 2 7658
TEST_TIME 3 6814
TEST_TIME 4 6104
TEST_TIME 5 5911
TEST_TIME 6 6071
TEST_TIME 7 5938
TEST_TIME 8 5897
TEST_TIME 9 6038
TEST_TIME 10 6119
TEST_TIME 11 6169
TEST_TIME 12 6403
TEST_TIME 13 6337
TEST_TIME 14 6371
TEST_TIME 15 6529
TEST_TIME 16 6478
TEST_TIME 17 6485
TEST_TIME 18 7101
TEST_TIME 19 7677
TEST_TIME 20 7899
TEST_TIME 21 8836
TEST_TIME 22 8912
TEST_TIME 23 9147
TEST_TIME 24 9345
TEST_TIME 25 9744
TEST_TIME 26 9643
TEST_TIME 27 9871
TEST_TIME 28 10158
TEST_TIME 29 10137
TEST_TIME 30 10640
TEST_TIME 31 10489
TEST_TIME 32 10309
TEST_TIME 33 10000
TEST_TIME 34 9787
TEST_TIME 35 9784
TEST_TIME 36 9596
TEST_TIME 37 9583
TEST_TIME 38 9501
TEST_TIME 39 9611
TEST_TIME 40 9457
TEST_TIME 41 9427
TEST_TIME 42 9532
TEST_TIME 43 9356
TEST_TIME 44 9323
TEST_TIME 45 9373
TEST_TIME 46 9230
TEST_TIME 47 9189
TEST_TIME 48 9069
TEST_TIME 49 9069
TEST_TIME 50 9183
TEST_TIME 51 9235
TEST_TIME 52 9211
TEST_TIME 53 9120
TEST_TIME 54 9233
TEST_TIME 55 9173
TEST_TIME 56 9193
TEST_TIME 57 9273
TEST_TIME 58 9277
TEST_TIME 59 9282
TEST_TIME 60 9300
TEST_TIME 61 9280
TEST_TIME 62 9342
TEST_TIME 63 9236
TEST_TIME 64 9138
TEST_TIME 65 9043
TEST_TIME 66 9049
TEST_TIME 67 9078
TEST_TIME 68 9034
TEST_TIME 69 8982
TEST_TIME 70 8922
TEST_TIME 71 8857
TEST_TIME 72 8840
TEST_TIME 73 8849
TEST_TIME 74 8811
TEST_TIME 75 8782
TEST_TIME 76 8823
TEST_TIME 77 8929
TEST_TIME 78 8934
TEST_TIME 79 8975
TEST_TIME 80 9042
TEST_TIME 81 9091
TEST_TIME 82 9053
TEST_TIME 83 9095
TEST_TIME 84 9205
TEST_TIME 85 9138
TEST_TIME 86 9055
TEST_TIME 87 9032
TEST_TIME 88 8983
TEST_TIME 89 8928
TEST_TIME 90 8861
TEST_TIME 91 8819
TEST_TIME 92 8870
TEST_TIME 93 8867
TEST_TIME 94 8910
TEST_TIME 95 8936
TEST_TIME 96 8925
TEST_TIME 97 8938
TEST_TIME 98 8926
TEST_TIME 99 8913
TEST_TIME 100 8939
TEST_TIME 101 8963
TEST_TIME 102 8957
TEST_TIME 103 8994
TEST_TIME 104 9044
TEST_TIME 105 8984
TEST_TIME 106 8923
TEST_TIME 107 8887
TEST_TIME 108 8889
TEST_TIME 109 8856
TEST_TIME 110 8801
TEST_TIME 111 8793
TEST_TIME 112 8768
TEST_TIME 113 8765
TEST_TIME 114 8735
TEST_TIME 115 8799
TEST_TIME 116 8767
TEST_TIME 117 8759
TEST_TIME 118 8746
TEST_TIME 119 8786
TEST_TIME 120 8715

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-08-2021 13:38:20 to 13-08-2021 13:38:23.

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 1452571 1452571 0 0.00 0 32939 7 0 0
doc_list_biud 1452571 1452571 0 0.00 0 34548 33 0 0
sp_add_invoice_to_stock 4925 4757 168 3.41 50 324257 20786 0 168
sp_cancel_adding_invoice 5252 5252 0 0.00 4 82188 907 0 0
sp_cancel_client_order 1663 1663 0 0.00 4 23402 372 0 0
sp_cancel_customer_reserve 523804 523804 0 0.00 1 31187 72 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 6811 129 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 1816 77 0 0
sp_cancel_supplier_invoice 5826 5810 16 0.27 2 21986 1052 0 16
sp_cancel_supplier_order 390 390 0 0.00 6 1147775 123243 0 0
sp_cancel_write_off 8683 8683 0 0.00 1 27183 126 0 0
sp_client_order 19068 19068 0 0.00 4 74507 463 0 0
sp_customer_reserve 442742 442742 0 0.00 0 47910 203 0 0
sp_fill_shopping_cart 29274 29274 0 0.00 1 23718 49 0 0
sp_fill_shopping_cart_clo_res 442735 442735 0 0.00 0 20635 17 0 0
sp_get_clo_for_invoice 4750 4750 0 0.00 0 33327 412 0 0
sp_kill_cost_storno 14990 14990 0 0.00 0 28319 69 0 0
sp_kill_qstorno_ret_qs2qd 1062491 1062491 0 0.00 0 31182 39 0 0
sp_kill_qty_storno 559627 559627 0 0.00 0 34548 84 0 0
sp_lock_selected_doc 19443 19443 0 0.00 0 10008 6 0 0
sp_make_cost_storno 25772 25772 0 0.00 0 32937 71 0 0
sp_multiply_rows_for_pdistr 25772 25772 0 0.00 0 10191 36 0 0
sp_multiply_rows_for_qdistr 19068 19068 0 0.00 0 74497 235 0 0
sp_pay_from_customer 6355 6355 0 0.00 4 24690 148 0 0
sp_pay_to_supplier 5392 5392 0 0.00 5 20308 137 0 0
sp_payment_common 11747 11747 0 0.00 1 7082 26 0 0
sp_qd_handle_on_cancel_clo 1663 1663 0 0.00 0 23392 330 0 0
sp_qd_handle_on_invoice_upd_sts 10002 10002 0 0.00 0 34547 401 0 0
sp_qd_handle_on_reserve_upd_sts 9275 9275 0 0.00 0 10326 58 0 0
sp_reserve_write_off 9275 9275 0 0.00 6 32951 209 0 0
sp_supplier_invoice 5048 5048 0 0.00 7 128279 3725 0 0
sp_supplier_order 5170 5170 0 0.00 5 63703 1592 0 0
srv_aggregate_perf_data 105 105 0 0.00 2348 124587 16009 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1472 150836 45751 0 0
srv_find_qd_qs_mism 999707 999707 0 0.00 0 32245 6 0 0
srv_make_invnt_saldo 382 382 0 0.00 60 111012 4589 0 0
srv_make_money_saldo 641 641 0 0.00 1 22630 792 0 0
srv_recalc_idx_stat 4 4 0 0.00 97443 439799 246001 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 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 4758 4758 0 0.00 0 13112 193 0 0
t$perf-norm:sp_cancel_adding_invoice 357 357 0 0.00 0 3274 96 0 0
t$perf-norm:sp_cancel_client_order 1663 1663 0 0.00 0 1515 8 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 951 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 5598 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 316 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 469 16 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 19296 1612 0 0
t$perf-norm:sp_cancel_write_off 919 919 0 0.00 0 602 5 0 0
t$perf-norm:sp_client_order 19068 19068 0 0.00 0 11382 20 0 0
t$perf-norm:sp_customer_reserve 936 936 0 0.00 0 992 7 0 0
t$perf-norm:sp_pay_from_customer 6355 6355 0 0.00 0 10365 11 0 0
t$perf-norm:sp_pay_to_supplier 5392 5392 0 0.00 0 9124 9 0 0
t$perf-norm:sp_reserve_write_off 9275 9275 0 0.00 0 5312 8 0 0
t$perf-norm:sp_supplier_invoice 5048 5048 0 0.00 0 5188 21 0 0
t$perf-norm:sp_supplier_order 5170 5170 0 0.00 0 3789 17 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 370 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 6 1 0 0
t$perf-norm:srv_make_invnt_saldo 382 382 0 0.00 0 538 5 0 0
t$perf-norm:srv_make_money_saldo 641 641 0 0.00 0 10557 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9834 9498 336 3.41 0 20766 26 0 336
v_all_customers 34436 34436 0 0.00 0 19 1 0 0
v_all_suppliers 27954 27954 0 0.00 0 15 1 0 0
v_all_wares 38136 38136 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 714 714 0 0.00 0 239 5 0 0
v_cancel_client_order 3326 3326 0 0.00 0 19233 13 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 6803 16 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 2091 7 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1810 8 0 0
v_cancel_supplier_invoice 794 762 32 4.03 0 194 11 0 32
v_cancel_supplier_order 780 780 0 0.00 0 2585 18 0 0
v_cancel_write_off 1838 1838 0 0.00 0 5588 16 0 0
v_max_id_clo_ord 5170 5170 0 0.00 0 2398 2 0 0
v_max_id_clo_res 936 936 0 0.00 0 4077 23 0 0
v_max_id_ord_sup 5036 5036 0 0.00 0 2074 3 0 0
v_max_non_paid_invoice 1621 1621 0 0.00 0 2666 4 0 0
v_max_non_paid_realizn 3437 3437 0 0.00 0 2680 5 0 0
v_min_id_clo_ord 5170 5170 0 0.00 0 2625 7 0 0
v_min_id_clo_res 936 936 0 0.00 0 4639 15 0 0
v_min_id_ord_sup 5036 5036 0 0.00 0 10196 12 0 0
v_min_non_paid_invoice 5392 5392 0 0.00 0 6993 15 0 0
v_min_non_paid_realizn 6355 6355 0 0.00 0 4414 19 0 0
v_our_firm 9536 9536 0 0.00 0 19 1 0 0
v_reserve_write_off 18550 18550 0 0.00 0 20653 8 0 0
x_lock_depdocs_on_canc_invoice 10681 10681 0 0.00 0 16313 113 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 12236 399 0 0
x_make_qstorno_1000_1200 5170 5170 0 0.00 2 63598 1503 0 0
x_make_qstorno_1200_2000 5048 5048 0 0.00 3 128242 3535 0 0
x_make_qstorno_2100_3300 442742 442742 0 0.00 0 47909 182 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-08-2021 13:38:24 to 13-08-2021 13:38:28.

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
11:07:10 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 25789 8192000000 9720280608 10018971648 793021904 8.826 100 84 168 0 821458096 7966848 20469344
11:09:43 21473 8192000000 9760030272 10067886080 832457312 9.224 100 89 178 0 866112672 12351792 21303568
11:11:14 23009 8192000000 9808310176 10130386944 877746672 9.677 100 77 153 2 917717392 22237920 17732800
11:12:45 9604 8192000000 9854673376 10188845056 920533408 10.101 100 77 154 0 966949264 29197072 17218784
11:14:01 9354 8192000000 9920246976 10264203264 954036240 10.431 100 89 179 1 1033132416 60284512 18811664
11:15:14 8498 8192000000 9949257424 10305523712 984885584 10.732 100 72 144 0 1061400464 61979920 14534960
11:16:25 5870 8192000000 9973568592 10343022592 1017477408 11.048 100 64 128 0 1094645792 63955568 13212816
11:17:35 2256 8192000000 10017678112 10381565952 1042072112 11.285 100 90 181 0 1124862656 65148544 17642000
11:18:40 1775 8192000000 10026393360 10398347264 1055130336 11.410 100 89 175 1 1140066928 67008080 17928512
11:19:43 7368 8192000000 9986393616 10375344128 1059418512 11.451 100 35 70 0 1120940864 54590128 6932224
11:20:54 4919 8192000000 10003936656 10387873792 1073077600 11.581 100 56 112 1 1109766112 25102720 11585792
11:22:00 21221 8192000000 10009540112 10394439680 1075004832 11.600 100 68 136 2 1113785424 23512880 15267712
11:23:22 7177 8192000000 10034699584 10436911104 1082672704 11.673 100 46 92 0 1140147504 47485904 9988896
11:24:32 9174 8192000000 9999346576 10404147200 1086444784 11.709 100 32 65 1 1116193888 22409616 7339488
11:25:44 63767 8192000000 10007118448 10420494336 1093533248 11.776 100 34 68 2 1116084496 14486800 8064448
11:27:49 5596 8192000000 10017198112 10414956544 1089730656 11.740 100 46 92 1 1126034336 26830720 9472960
11:28:55 7889 8192000000 9966777216 10347499520 1021068384 11.082 96 37 74 2 1065529248 36789152 7671712
11:30:04 7082 8192000000 9943288736 10330427392 1014453184 11.018 97 32 64 3 1041713568 20156704 7103680
11:31:15 8576 8192000000 9917053104 10280767488 950018768 10.391 92 65 129 1 977594368 12819152 14756448
11:32:23 5239 8192000000 9968759072 10345603072 1022184416 11.093 100 44 88 0 1065192896 33907232 9101248
11:33:29 8389 8192000000 9944124944 10327973888 1038865872 11.254 100 37 73 2 1059527056 12538400 8122784
11:34:40 16295 8192000000 9966090128 10370465792 1052836048 11.388 100 25 49 2 1085608576 27182368 5590160
11:36:00 13439 8192000000 9988440304 10392735744 1060375968 11.460 100 26 50 2 1102034288 36368560 5289760
11:37:16 2343 8192000000 9998706992 10389524480 1078231840 11.631 100 43 87 1 1110663936 22403184 10028912
11:38:25 6086 8192000000 10059479920 10455810048 1097135888 11.810 100 69 136 0 1169106816 58620512 13350416
11:39:42 8457 8192000000 10043367120 10442309632 1109672032 11.929 100 50 100 3 1163009104 43402032 9935040
11:40:52 24547 8192000000 10085707520 10494902272 1121282096 12.039 100 37 73 0 1190657168 61485968 7889104
11:42:30 14888 8192000000 10122100304 10532249600 1131179136 12.132 100 39 78 1 1232100864 92298176 8623552
11:43:50 4511 8192000000 10168984720 10577092608 1154218480 12.349 100 58 117 1 1279474544 113210064 12046000
11:45:00 11964 8192000000 10218460208 10627440640 1160924448 12.412 100 60 120 1 1319897728 145762352 13210928
11:46:17 3312 8192000000 10257467664 10661679104 1173443760 12.529 100 66 129 2 1359673488 171863680 14366048
11:47:29 13506 8192000000 10273576704 10688180224 1182202976 12.611 100 61 121 0 1371038528 175023840 13811712
11:48:50 3615 8192000000 10277340656 10707288064 1188249728 12.667 100 67 130 3 1383292608 180497888 14544992
11:50:02 2690 8192000000 10326803328 10737381376 1192655440 12.708 100 83 166 2 1430285264 218544544 19085280
11:51:09 2151 8192000000 10364618336 10773250048 1199613744 12.773 100 87 168 2 1466906608 246853344 20439520
11:52:16 2498 8192000000 10415910352 10818043904 1186262272 12.649 100 88 175 0 1514293840 307644656 20386912
11:53:25 6753 8192000000 10428897344 10841559040 1167059824 12.469 99 65 130 0 1525639216 342761360 15818032
11:54:37 1471 8192000000 10499798160 10906648576 1166993968 12.469 100 88 173 1 1600705888 413055552 20656368
11:55:47 4411 8192000000 10428016240 10849280000 1144562832 12.258 99 66 131 1 1525334352 365317600 15453920
11:57:01 5431 8192000000 10430283856 10873012224 1164445376 12.445 100 69 138 0 1544820656 363669584 16705696
11:58:18 11935 8192000000 10267784400 10741313536 1130407024 12.125 99 29 58 2 1402066224 264321088 7338112
11:59:37 10556 8192000000 10279114128 10705272832 1145415920 12.266 100 40 78 1 1382486128 227560032 9510176
12:01:03 1679 8192000000 10258699888 10673344512 1127957840 12.102 99 77 152 1 1350788224 205040240 17790144
12:02:07 12135 8192000000 10175648288 10626945024 1130356304 12.125 100 29 56 2 1292540288 154736512 7447472
12:03:21 14164 8192000000 10204168576 10637955072 1122935616 12.055 100 32 64 1 1295755248 165243824 7575808
12:04:41 19211 8192000000 10206989328 10656645120 1129946960 12.121 100 14 28 0 1311746800 177339072 4460768
12:06:05 46071 8192000000 10139891808 10578477056 1113370736 11.964 99 17 34 0 1237621808 120607088 3643984
12:07:52 33985 8192000000 10086402608 10514866176 1125622352 12.080 99 40 81 2 1173844752 40205792 8016608
12:09:32 143856 8192000000 10148988352 10583863296 1128027296 12.103 99 41 81 1 1227250128 90830048 8392784
12:12:57 65078 8192000000 10187348144 10624655360 1141135360 12.226 99 29 58 1 1255786544 106934560 7716624
12:15:05 42399 8192000000 10167107776 10631532544 1137867024 12.195 100 32 65 1 1268986800 123930464 7189312
12:16:56 58923 8192000000 10133395920 10576424960 1128329632 12.106 99 27 52 0 1228725760 94432240 5963888
12:18:55 70459 8192000000 10105707488 10614104064 1121523776 12.041 99 22 44 0 1222521776 96056928 4941072
12:21:28 29495 8192000000 10113806496 10556108800 1135018384 12.169 100 25 50 3 1240159824 99609744 5531696
12:22:59 31270 8192000000 10110903408 10546192384 1137098624 12.188 99 29 59 2 1206437440 63149696 6189120
12:24:34 113345 8192000000 10148491296 10643628032 1139172272 12.208 99 23 46 2 1258813248 114564480 5076496
12:28:36 83588 8192000000 10099868960 10594385920 1130169168 12.123 99 14 28 1 1191521024 58065168 3286688
12:31:10 61797 8192000000 10064091600 10535968768 1130107856 12.122 99 20 40 1 1213330784 78905504 4317424
12:33:17 79446 8192000000 10068838896 10531278848 1126128560 12.085 99 18 36 0 1192667488 62969312 3569616
12:35:38 77205 8192000000 10127802928 10618040320 1138655824 12.203 99 26 52 3 1220075600 76177872 5241904
12:38:07 65553 8192000000 10104168272 10552205312 1091041168 11.753 96 22 44 2 1137660448 42103024 4516256
12:40:13 104617 8192000000 10104699488 10539757568 1090392912 11.746 95 45 90 1 1128189504 27966560 9830032
12:43:11 2043 8192000000 10104042944 10537615360 1119484288 12.022 99 48 95 2 1196039616 66762048 9793280
12:44:20 72397 8192000000 10064239600 10519908352 1122874864 12.054 99 15 30 0 1162204448 36785296 2544288
12:46:37 63905 8192000000 10046933744 10516561920 1106171168 11.896 98 21 42 0 1147394144 36998816 4224160
12:49:03 58998 8192000000 10082533872 10562502656 1141282688 12.228 100 6 12 1 1187249136 45009600 956848
12:51:12 91444 8192000000 10145800960 10595905536 1049888848 11.360 92 27 54 0 1135278016 79875600 5513568
12:53:44 47797 8192000000 10161520688 10634891264 1118930848 12.017 99 22 42 2 1285991872 161585120 5475904
12:55:33 79874 8192000000 10123610592 10575278080 1106009536 11.895 97 21 42 1 1182185760 71701296 4474928
12:57:56 43076 8192000000 10158577424 10599059456 1119831600 12.025 99 25 50 2 1212062944 86943456 5287888
12:59:49 49414 8192000000 10138599952 10584657920 1119816192 12.025 99 37 73 0 1226832608 99188848 7827568
13:01:39 116483 8192000000 10091877952 10575773696 1126465104 12.088 99 11 22 1 1182912688 54470800 1976784
13:04:45 60835 8192000000 10062667456 10526310400 1104732320 11.883 98 9 18 0 1141888992 35536304 1620368
13:06:47 45580 8192000000 10094504592 10576998400 1121447264 12.041 100 5 10 1 1167255536 44718816 1089456
13:08:41 102585 8192000000 10132332432 10662309888 1144253552 12.256 100 14 28 0 1247339360 100262784 2823024
13:11:27 105657 8192000000 10091178704 10564325376 1136748096 12.185 99 18 36 0 1171829792 31323856 3757840
13:14:13 43999 8192000000 10078667024 10524512256 1148706576 12.297 100 23 47 0 1181261424 27316640 5238208
13:16:00 33535 8192000000 10113550016 10548936704 1128548256 12.108 99 45 89 1 1189675696 51912048 9215392
13:17:36 46527 8192000000 10101609280 10549714944 1135742224 12.175 99 29 58 0 1189955440 48220992 5992224
13:19:34 57178 8192000000 10083596496 10599227392 1110247040 11.935 97 25 50 0 1178057120 62883200 4926880
13:22:02 150836 8192000000 10049868336 10494885888 1086390080 11.708 96 17 34 1 1144454384 54125776 3938528
13:25:34 70209 8192000000 10078313088 10583552000 1128730672 12.109 99 12 24 0 1189415024 58217232 2467120
13:29:07 62857 8192000000 10089317072 10591723520 1121966432 12.046 99 13 26 0 1199704816 74615152 3123232
13:31:17 39580 8192000000 10112082784 10575396864 1126302448 12.086 100 26 52 0 1210809696 78773072 5734176
13:33:11 68429 8192000000 10078723744 10533965824 1106811424 11.902 97 19 39 0 1150606672 39052320 4742928
13:35:27 73580 8192000000 10108029872 10591219712 1129465488 12.116 98 34 68 3 1237985904 100695200 7825216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-08-2021 13:38:30 to 13-08-2021 13:38:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 168 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 13-08-2021 13:38:46 to 13-08-2021 13:38:48.

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          222025
MON$OLDEST_ACTIVE               222355
MON$OLDEST_SNAPSHOT             222355
MON$NEXT_TRANSACTION            222355
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                       2043488
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2569 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2569 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2569 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2569 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Fri Aug 13 13:37:29 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

13.08.21 13:38:49. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-08-13 13:38:52 No severe PSQL-related problems occured
13.08.21 13:38:53. End of report.