Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.06.2021 13:27
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 16558032 kB
MemAvailable 31330408 kB
Buffers 0 kB
Cached 15063192 kB
SwapCached 2416 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-06-22 13:27:01
DTS_END                         2021-06-22 13:27:01.5650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60820
TRN_ID                          226492
ATT_ID                          660
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: 8871.08 22752 1064529
customer order: creation 167.05 416 20046
customer order: refuse 14.66 404 1759
order to supplier: creation 44.78 1431 5374
order to supplier: removal 3.21 126404 385
invoice (draft): creation 44.90 3955 5388
invoice (draft): removal 50.40 973 6048
invoice accept: apply 41.44 18337 4973
invoice accept: cancel 45.65 1040 5478
customer reserve: creation 3720.43 181 446451
customer reserve: removal 4453.70 72 534444
realization accept: apply 81.41 190 9769
realization accept: cancel 77.57 128 9308
payment from customer: creation 58.23 108 6987
payment from customer: removal 5.18 97 621
payment to supplier: creation 48.59 132 5831
payment to supplier: removal 3.39 86 407
service: total inventory turnovers 3.56 4363 427
service: total monetary turnovers 6.06 659 727
service: aggregate perf. data 0.85 14819 102
service: refresh index statistics 0.03 281239 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-06-2021 13:27:48 to 22-06-2021 13:27:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9983
TEST_TIME 2 6934
TEST_TIME 3 7319
TEST_TIME 4 7560
TEST_TIME 5 6976
TEST_TIME 6 6937
TEST_TIME 7 6627
TEST_TIME 8 6557
TEST_TIME 9 6659
TEST_TIME 10 6715
TEST_TIME 11 6916
TEST_TIME 12 7153
TEST_TIME 13 7270
TEST_TIME 14 7287
TEST_TIME 15 7357
TEST_TIME 16 7591
TEST_TIME 17 7894
TEST_TIME 18 7927
TEST_TIME 19 8180
TEST_TIME 20 8382
TEST_TIME 21 8374
TEST_TIME 22 8853
TEST_TIME 23 9056
TEST_TIME 24 9228
TEST_TIME 25 9339
TEST_TIME 26 9333
TEST_TIME 27 9445
TEST_TIME 28 9576
TEST_TIME 29 10373
TEST_TIME 30 10906
TEST_TIME 31 10823
TEST_TIME 32 10981
TEST_TIME 33 10731
TEST_TIME 34 10511
TEST_TIME 35 10357
TEST_TIME 36 10318
TEST_TIME 37 10231
TEST_TIME 38 10135
TEST_TIME 39 9955
TEST_TIME 40 9821
TEST_TIME 41 9838
TEST_TIME 42 9652
TEST_TIME 43 9582
TEST_TIME 44 9574
TEST_TIME 45 9638
TEST_TIME 46 9670
TEST_TIME 47 9563
TEST_TIME 48 9589
TEST_TIME 49 9713
TEST_TIME 50 9697
TEST_TIME 51 9676
TEST_TIME 52 9570
TEST_TIME 53 9511
TEST_TIME 54 9379
TEST_TIME 55 9264
TEST_TIME 56 9282
TEST_TIME 57 9256
TEST_TIME 58 9180
TEST_TIME 59 9211
TEST_TIME 60 9143
TEST_TIME 61 9124
TEST_TIME 62 9089
TEST_TIME 63 8985
TEST_TIME 64 9000
TEST_TIME 65 8988
TEST_TIME 66 8959
TEST_TIME 67 8922
TEST_TIME 68 8889
TEST_TIME 69 8861
TEST_TIME 70 8862
TEST_TIME 71 9046
TEST_TIME 72 9087
TEST_TIME 73 9087
TEST_TIME 74 9080
TEST_TIME 75 9039
TEST_TIME 76 9222
TEST_TIME 77 9208
TEST_TIME 78 9276
TEST_TIME 79 9231
TEST_TIME 80 9126
TEST_TIME 81 9094
TEST_TIME 82 9110
TEST_TIME 83 9054
TEST_TIME 84 9024
TEST_TIME 85 8962
TEST_TIME 86 8911
TEST_TIME 87 8896
TEST_TIME 88 8874
TEST_TIME 89 8867
TEST_TIME 90 8834
TEST_TIME 91 8856
TEST_TIME 92 8830
TEST_TIME 93 8816
TEST_TIME 94 8819
TEST_TIME 95 8840
TEST_TIME 96 8869
TEST_TIME 97 9006
TEST_TIME 98 9016
TEST_TIME 99 9000
TEST_TIME 100 9001
TEST_TIME 101 9052
TEST_TIME 102 9067
TEST_TIME 103 9055
TEST_TIME 104 8969
TEST_TIME 105 8997
TEST_TIME 106 8970
TEST_TIME 107 8932
TEST_TIME 108 8886
TEST_TIME 109 8887
TEST_TIME 110 8861
TEST_TIME 111 8863
TEST_TIME 112 8874
TEST_TIME 113 8858
TEST_TIME 114 8880
TEST_TIME 115 8857
TEST_TIME 116 8866
TEST_TIME 117 8917
TEST_TIME 118 8954
TEST_TIME 119 8956
TEST_TIME 120 8882

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-06-2021 13:27:52 to 22-06-2021 13:27:55.

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 1478104 1478104 0 0.00 0 23896 7 0 0
doc_list_biud 1478102 1478102 0 0.00 0 39836 33 0 0
sp_add_invoice_to_stock 5101 4973 128 2.50 24 160602 18338 0 128
sp_cancel_adding_invoice 5478 5478 0 0.00 5 93690 1040 0 0
sp_cancel_client_order 1759 1759 0 0.00 5 12796 405 0 0
sp_cancel_customer_reserve 534444 534444 0 0.00 1 39839 72 0 0
sp_cancel_pay_from_customer 621 621 0 0.00 3 5476 97 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 2593 87 0 0
sp_cancel_supplier_invoice 6058 6048 10 0.16 2 32109 974 0 10
sp_cancel_supplier_order 385 385 0 0.00 9 1301376 126404 0 0
sp_cancel_write_off 9308 9308 0 0.00 1 10226 129 0 0
sp_client_order 20046 20046 0 0.00 4 29453 417 0 0
sp_customer_reserve 446451 446451 0 0.00 0 125825 181 0 0
sp_fill_shopping_cart 30789 30789 0 0.00 1 24567 50 0 0
sp_fill_shopping_cart_clo_res 446437 446437 0 0.00 0 24241 16 0 0
sp_get_clo_for_invoice 4950 4950 0 0.00 0 26452 422 0 0
sp_kill_cost_storno 15814 15814 0 0.00 0 14905 67 0 0
sp_kill_qstorno_ret_qs2qd 1084629 1084629 0 0.00 0 39833 39 0 0
sp_kill_qty_storno 572139 572139 0 0.00 0 39835 85 0 0
sp_lock_selected_doc 20368 20368 0 0.00 0 11438 4 0 0
sp_make_cost_storno 27535 27535 0 0.00 0 14479 58 0 0
sp_multiply_rows_for_pdistr 27535 27535 0 0.00 0 12424 36 0 0
sp_multiply_rows_for_qdistr 20046 20046 0 0.00 0 29430 232 0 0
sp_pay_from_customer 6987 6987 0 0.00 4 22475 109 0 0
sp_pay_to_supplier 5831 5831 0 0.00 5 14475 132 0 0
sp_payment_common 12818 12818 0 0.00 1 8425 19 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 12789 371 0 0
sp_qd_handle_on_invoice_upd_sts 10426 10426 0 0.00 0 18745 426 0 0
sp_qd_handle_on_reserve_upd_sts 9769 9769 0 0.00 0 18668 63 0 0
sp_reserve_write_off 9769 9769 0 0.00 6 18703 191 0 0
sp_supplier_invoice 5388 5388 0 0.00 6 144566 3955 0 0
sp_supplier_order 5374 5374 0 0.00 6 30059 1432 0 0
srv_aggregate_perf_data 102 102 0 0.00 2607 158609 14819 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1856 147871 45611 0 0
srv_find_qd_qs_mism 1018215 1018215 0 0.00 0 23729 6 0 0
srv_make_invnt_saldo 427 427 0 0.00 39 136086 4363 0 0
srv_make_money_saldo 727 727 0 0.00 1 18436 660 0 0
srv_recalc_idx_stat 4 4 0 0.00 167654 362870 281240 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4973 4973 0 0.00 0 14269 197 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 9049 148 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 1502 13 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 906 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 621 621 0 0.00 0 14558 29 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 605 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 1105 15 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 33456 1909 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 3552 14 0 0
t$perf-norm:sp_client_order 20046 20046 0 0.00 0 14603 23 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 13451 19 0 0
t$perf-norm:sp_pay_from_customer 6987 6987 0 0.00 0 12231 13 0 0
t$perf-norm:sp_pay_to_supplier 5831 5831 0 0.00 0 11757 12 0 0
t$perf-norm:sp_reserve_write_off 9769 9769 0 0.00 0 12213 13 0 0
t$perf-norm:sp_supplier_invoice 5388 5388 0 0.00 0 10359 23 0 0
t$perf-norm:sp_supplier_order 5374 5374 0 0.00 0 20434 28 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 103 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 936 18 0 0
t$perf-norm:srv_make_invnt_saldo 427 427 0 0.00 0 2827 24 0 0
t$perf-norm:srv_make_money_saldo 727 727 0 0.00 0 3772 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10152 9896 256 2.52 0 5273 16 0 256
v_all_customers 36546 36546 0 0.00 0 16 1 0 0
v_all_suppliers 29770 29770 0 0.00 0 15 1 0 0
v_all_wares 40090 40090 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 187 6 0 0
v_cancel_client_order 3518 3518 0 0.00 0 1543 5 0 0
v_cancel_customer_prepayment 1242 1242 0 0.00 0 2374 9 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 817 4 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 1026 5 0 0
v_cancel_supplier_invoice 812 792 20 2.46 0 2918 16 0 20
v_cancel_supplier_order 770 770 0 0.00 0 1482 10 0 0
v_cancel_write_off 2044 2044 0 0.00 0 5420 17 0 0
v_max_id_clo_ord 5373 5373 0 0.00 0 340 1 0 0
v_max_id_clo_res 989 989 0 0.00 0 842 7 0 0
v_max_id_ord_sup 5370 5370 0 0.00 0 6384 6 0 0
v_max_non_paid_invoice 1688 1688 0 0.00 0 453 2 0 0
v_max_non_paid_realizn 3724 3724 0 0.00 0 1670 3 0 0
v_min_id_clo_ord 5373 5373 0 0.00 0 2236 7 0 0
v_min_id_clo_res 989 989 0 0.00 0 7319 30 0 0
v_min_id_ord_sup 5370 5370 0 0.00 0 19120 7 0 0
v_min_non_paid_invoice 5831 5831 0 0.00 0 7743 15 0 0
v_min_non_paid_realizn 6987 6987 0 0.00 0 8420 11 0 0
v_our_firm 10070 10070 0 0.00 0 12 1 0 0
v_reserve_write_off 19538 19538 0 0.00 0 4823 6 0 0
x_lock_depdocs_on_canc_invoice 11130 11130 0 0.00 0 28775 121 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 18484 447 0 0
x_make_qstorno_1000_1200 5374 5374 0 0.00 2 30055 1342 0 0
x_make_qstorno_1200_2000 5388 5388 0 0.00 3 138915 3751 0 0
x_make_qstorno_2100_3300 446451 446451 0 0.00 0 125824 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-06-2021 13:27:56 to 22-06-2021 13:28:00.

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
10:57:05 1 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
10:58:05 27574 8192000000 9719096096 10013032448 794766384 8.843 100 91 182 1 825336080 8106864 22462832
10:59:39 16958 8192000000 9747058368 10052341760 825469744 9.154 100 89 177 1 858968256 12150288 21348224
11:01:02 18996 8192000000 9796302144 10116161536 868477536 9.585 100 80 160 1 903197008 16605568 18113904
11:02:25 12633 8192000000 9866090224 10196365312 909704816 9.994 100 82 164 1 971370816 43831040 17834960
11:03:41 12686 8192000000 9913915024 10258190336 948877584 10.380 100 78 156 3 1024898208 59852688 16167936
11:05:02 7946 8192000000 9937458992 10293506048 984319776 10.726 100 77 153 0 1060612288 60528800 15763712
11:06:16 4594 8192000000 9997268784 10355965952 1010649280 10.982 100 88 174 2 1103821808 75380928 17791600
11:07:23 2392 8192000000 10009244016 10376577024 1039335888 11.258 100 81 163 1 1122648528 66959616 16353024
11:08:27 1462 8192000000 10033032880 10400366592 1061563600 11.471 100 89 177 1 1142278576 62624256 18090720
11:09:32 2069 8192000000 10065290144 10439598080 1068952688 11.542 100 97 196 2 1178242624 89248816 20041120
11:10:36 7946 8192000000 10022402176 10425475072 1073798896 11.588 100 54 108 0 1134132784 49560352 10773536
11:11:48 4529 8192000000 10012946464 10402234368 1083534528 11.681 100 54 108 3 1132505248 36553376 12417344
11:12:55 10089 8192000000 10000635776 10397564928 1085541920 11.700 100 43 86 2 1117129296 22671984 8915392
11:14:08 5368 8192000000 10015677408 10417201152 1095636080 11.796 100 40 79 1 1143135904 38781072 8718752
11:15:15 7041 8192000000 10007152976 10406150144 1099762560 11.835 100 49 97 0 1124088016 13432336 10893120
11:16:24 27643 8192000000 10005809104 10427117568 1105371408 11.889 100 25 51 1 1128723424 18102704 5249312
11:17:52 9018 8192000000 10038940496 10458910720 1097578192 11.815 99 25 50 0 1165034160 62579296 4876672
11:19:02 7307 8192000000 10023508800 10446725120 1107127536 11.905 100 46 93 2 1143968576 26004096 10836944
11:20:10 5893 8192000000 9983912832 10376769536 1065725760 11.511 99 36 70 2 1094595200 20935088 7934352
11:21:21 69575 8192000000 9934639568 10331799552 918824480 10.084 88 30 60 1 947028256 22044400 6159376
11:23:33 4272 8192000000 9934670720 10314747904 1022134336 11.093 100 42 81 3 1046804336 15797024 8872976
11:24:40 7405 8192000000 9965600672 10352111616 1034109328 11.208 99 44 87 1 1052401008 8142816 10148864
11:25:50 6741 8192000000 9974255488 10366111744 1053129696 11.391 100 38 76 0 1084406784 22341872 8935216
11:27:00 71888 8192000000 10020349440 10417078272 1074200480 11.592 100 47 93 1 1107951872 23023632 10727760
11:29:36 17957 8192000000 10054537248 10459774976 1098031248 11.819 100 40 80 0 1167150576 60996576 8122752
11:30:58 9832 8192000000 10081077232 10504974336 1113518832 11.966 100 38 75 0 1193423168 72016608 7887728
11:32:13 11692 8192000000 10089225616 10495475712 1127504544 12.098 100 61 121 0 1194857776 54403456 12949776
11:33:31 14713 8192000000 10116601872 10538622976 1138928384 12.205 100 40 80 1 1249125920 101201712 8995824
11:35:00 7104 8192000000 10183835456 10586660864 1155410704 12.360 100 71 140 0 1281747392 111522320 14814368
11:36:14 13024 8192000000 10206945296 10632716288 1161304832 12.415 100 47 93 1 1314675376 142533536 10837008
11:37:34 20798 8192000000 10243873264 10662293504 1169258880 12.490 100 63 124 0 1344023600 161259376 13505344
11:38:59 4147 8192000000 10265821184 10678292480 1181142064 12.601 100 73 144 1 1367362544 169933696 16286784
11:40:07 4860 8192000000 10316745616 10728083456 1181052736 12.600 100 75 147 2 1421623504 223963568 16607200
11:41:17 7040 8192000000 10346188048 10764001280 1189150032 12.675 100 73 145 1 1446271088 240507056 16614000
11:42:33 1900 8192000000 10343580512 10764550144 1186224224 12.648 100 85 171 0 1442441424 236659520 19557680
11:43:39 4269 8192000000 10363133792 10796482560 1184380512 12.631 100 70 141 1 1461511440 261739200 15391728
11:44:52 1852 8192000000 10367496128 10793451520 1167219216 12.471 99 85 170 1 1464528576 278115616 19193744
11:45:58 3382 8192000000 10401018880 10808614912 1165261360 12.453 100 82 163 0 1498148192 315081904 17804928
11:47:06 3375 8192000000 10396385072 10812801024 1159796464 12.401 100 62 124 0 1500502304 325712016 14993824
11:48:14 3055 8192000000 10406551120 10818203648 1145851600 12.271 100 79 156 1 1496913328 331661472 19400256
11:49:23 9162 8192000000 10422829008 10836529152 1141508928 12.230 100 58 116 0 1523656272 367921312 14226032
11:50:40 6553 8192000000 10357884272 10778439680 1125807168 12.082 98 42 84 1 1441858992 305439968 10611856
11:52:00 9493 8192000000 10360914112 10786017280 1145456560 12.267 100 37 74 1 1456755024 301615040 9683424
11:53:32 14382 8192000000 10349243936 10804002816 1143971280 12.253 100 28 56 0 1447704464 296417472 7315712
11:54:55 24972 8192000000 10340455136 10819264512 1133846880 12.158 99 27 54 1 1466555808 325766112 6942816
11:56:23 25818 8192000000 10204433632 10758422528 1121343360 12.040 100 20 40 0 1350724752 223860416 5520976
11:57:51 13315 8192000000 10079673168 10493001728 1136941664 12.187 100 51 99 2 1165513344 17526752 11044928
11:59:07 39865 8192000000 10105061184 10586537984 1129461024 12.116 99 7 14 0 1209831808 79234112 1136672
12:01:20 59840 8192000000 10075072560 10531401728 1130183664 12.123 99 23 45 2 1170140768 34880848 5076256
12:03:26 115828 8192000000 10063318288 10539167744 1124421824 12.069 99 7 14 0 1152853584 27135120 1296640
12:06:26 66997 8192000000 10098093824 10533732352 1107891712 11.912 97 33 61 2 1138762800 24554352 6316736
12:08:52 61958 8192000000 10095602448 10528899072 1116588448 11.995 98 37 72 2 1163542016 39071344 7882224
12:11:03 84514 8192000000 10118646000 10578468864 1117804864 12.006 98 15 30 1 1183892272 62629152 3458256
12:13:28 70600 8192000000 10064653952 10506285056 1124741536 12.072 99 13 24 1 1157802928 30843264 2218128
12:15:39 136483 8192000000 10083075488 10567897088 1134182080 12.161 99 15 30 0 1220584816 83203600 3199136
12:18:56 69481 8192000000 10079818480 10509594624 1129035712 12.112 99 29 58 1 1184447792 49182336 6229744
12:21:09 147870 8192000000 10115453088 10592972800 1139427104 12.210 100 28 56 0 1232353632 86806448 6120080
12:24:44 30765 8192000000 10073785568 10521100288 1139986320 12.215 100 17 34 2 1176209232 31585968 4636944
12:26:34 38081 8192000000 10089838704 10553102336 1144641360 12.259 100 20 39 2 1202343136 53286800 4414976
12:28:13 56523 8192000000 10075059584 10542338048 1106159584 11.896 96 15 31 0 1157621152 48444976 3016592
12:30:11 127467 8192000000 10086355984 10574737408 1100186896 11.839 96 14 28 1 1151551824 48585216 2779712
12:33:26 34797 8192000000 10086913104 10532540416 1145032496 12.263 100 20 40 0 1198712816 49836160 3844160
12:35:07 26285 8192000000 10124478688 10580594688 1109740256 11.930 97 30 60 2 1214231552 97718736 6772560
12:36:35 57422 8192000000 10166129424 10609745920 1106296560 11.897 97 24 48 0 1222372096 110916928 5158608
12:38:43 67246 8192000000 10082342080 10557784064 1118939168 12.017 99 13 26 1 1238242736 116467968 2835600
12:40:50 75862 8192000000 10127341856 10578010112 1108389984 11.917 98 22 43 0 1200051760 86597040 5064736
12:43:36 59514 8192000000 10106010640 10550837248 1086398480 11.708 96 26 53 1 1175652432 83049184 6204768
12:45:45 83056 8192000000 10121206176 10611806208 1120767472 12.034 99 18 36 0 1215085200 90155280 4162448
12:49:02 59704 8192000000 10050154336 10508267520 1115239088 11.982 99 13 26 0 1151067824 33209584 2619152
12:51:13 72000 8192000000 10076539344 10536968192 1126153184 12.085 99 13 26 0 1185583824 56116048 3314592
12:53:25 54979 8192000000 10043046320 10533670912 1098302480 11.822 97 11 22 1 1151496416 50931904 2262032
12:55:22 60867 8192000000 10052733648 10509692928 1117871440 12.007 99 10 20 0 1163369072 43068448 2429184
12:57:33 49063 8192000000 10088819936 10542977024 1114859008 11.978 98 20 40 0 1155141328 36275952 4006368
12:59:27 73786 8192000000 10114666576 10553446400 1111941872 11.951 97 15 30 1 1173438656 58840320 2656464
13:01:43 52015 8192000000 10133207120 10590568448 1119904320 12.026 98 32 65 2 1224077424 97503712 6669392
13:03:39 90403 8192000000 10092747472 10609025024 1113917328 11.969 98 25 50 0 1260924352 141454384 5552640
13:06:09 42981 8192000000 10124195056 10571247616 1118336608 12.011 98 27 54 1 1167513984 43408240 5769136
13:07:53 60096 8192000000 10091863312 10554490880 1124796816 12.072 99 24 48 1 1199037072 69043024 5197232
13:10:08 89347 8192000000 10127825840 10616401920 1124209040 12.067 99 6 12 0 1210876608 85466528 1201040
13:12:57 45576 8192000000 10080439808 10563010560 1126536128 12.089 99 13 26 1 1176836592 47763248 2537216
13:14:51 34282 8192000000 10076317776 10534944768 1138896064 12.205 100 5 10 0 1183531536 43600048 1035424
13:16:35 60369 8192000000 10103692128 10590355456 1145136528 12.264 100 16 32 0 1207108576 58258160 3713888
13:18:50 78246 8192000000 10100366672 10539532288 1121750320 12.044 98 27 53 1 1154803040 27578064 5474656
13:21:09 59335 8192000000 10116001728 10593964032 1125081648 12.075 98 27 54 0 1212969488 82203904 5683936
13:23:12 70210 8192000000 10116142736 10583146496 1090142848 11.744 96 16 32 0 1171347072 77747456 3456768
13:25:23 14724 8192000000 10064041536 10483867648 1117038528 11.999 99 43 87 1 1145408192 19679536 8690128
13:26:38 35998 8192000000 9114716480 9397682176 244692064 2.900 22 3 4 1 278353088 33083296 577728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-06-2021 13:28:02 to 22-06-2021 13:28:17.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-06-2021 13:28:18 to 22-06-2021 13:28:20.

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          226777
MON$OLDEST_ACTIVE               226778
MON$OLDEST_SNAPSHOT             226778
MON$NEXT_TRANSACTION            226778
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                       2109632
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2519 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2519 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2519 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2519 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:196: 
:197: mscs-test-161.budzdorov.ru    Tue Jun 22 13:27:19 2021
:198:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:199: 
--- end of diff output ---

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

22.06.21 13:28:21. 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-06-22 13:28:24 No severe PSQL-related problems occured
22.06.21 13:28:25. End of report.