Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.09.2021 10: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 15841736 kB
MemAvailable 30795084 kB
Buffers 0 kB
Cached 15822188 kB
SwapCached 416 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-09-18 10:37:05
DTS_END                         2021-09-18 10:37:05.3640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59802
TRN_ID                          239599
ATT_ID                          648
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: 8826.66 22696 1059199
customer order: creation 173.40 445 20808
customer order: refuse 14.38 335 1725
order to supplier: creation 46.45 1518 5574
order to supplier: removal 3.27 127704 392
invoice (draft): creation 45.81 3881 5497
invoice (draft): removal 48.48 1037 5818
invoice accept: apply 42.49 19229 5099
invoice accept: cancel 43.53 1010 5223
customer reserve: creation 3862.85 191 463542
customer reserve: removal 4263.41 78 511609
realization accept: apply 84.08 178 10090
realization accept: cancel 70.97 128 8516
payment from customer: creation 58.63 114 7036
payment from customer: removal 5.62 99 674
payment to supplier: creation 48.88 125 5865
payment to supplier: removal 3.53 89 424
service: total inventory turnovers 3.62 3868 434
service: total monetary turnovers 6.28 593 754
service: aggregate perf. data 0.95 16949 114
service: refresh index statistics 0.04 276345 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-09-2021 10:38:10 to 18-09-2021 10:38:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2217
TEST_TIME 2 3115
TEST_TIME 3 4044
TEST_TIME 4 4717
TEST_TIME 5 5286
TEST_TIME 6 5356
TEST_TIME 7 5494
TEST_TIME 8 5461
TEST_TIME 9 5693
TEST_TIME 10 5782
TEST_TIME 11 6428
TEST_TIME 12 6573
TEST_TIME 13 6905
TEST_TIME 14 7150
TEST_TIME 15 7222
TEST_TIME 16 7489
TEST_TIME 17 7922
TEST_TIME 18 7874
TEST_TIME 19 7881
TEST_TIME 20 8143
TEST_TIME 21 8552
TEST_TIME 22 8654
TEST_TIME 23 8724
TEST_TIME 24 8529
TEST_TIME 25 8986
TEST_TIME 26 9181
TEST_TIME 27 9155
TEST_TIME 28 9351
TEST_TIME 29 10217
TEST_TIME 30 11525
TEST_TIME 31 11155
TEST_TIME 32 11001
TEST_TIME 33 10933
TEST_TIME 34 10787
TEST_TIME 35 10670
TEST_TIME 36 10573
TEST_TIME 37 10349
TEST_TIME 38 10301
TEST_TIME 39 10152
TEST_TIME 40 10026
TEST_TIME 41 9857
TEST_TIME 42 9701
TEST_TIME 43 9797
TEST_TIME 44 9787
TEST_TIME 45 9712
TEST_TIME 46 9592
TEST_TIME 47 9529
TEST_TIME 48 9400
TEST_TIME 49 9365
TEST_TIME 50 9268
TEST_TIME 51 9280
TEST_TIME 52 9167
TEST_TIME 53 9186
TEST_TIME 54 9080
TEST_TIME 55 9094
TEST_TIME 56 9002
TEST_TIME 57 9008
TEST_TIME 58 8999
TEST_TIME 59 8966
TEST_TIME 60 9072
TEST_TIME 61 9019
TEST_TIME 62 9135
TEST_TIME 63 9104
TEST_TIME 64 9169
TEST_TIME 65 9244
TEST_TIME 66 9172
TEST_TIME 67 9207
TEST_TIME 68 9204
TEST_TIME 69 9184
TEST_TIME 70 9111
TEST_TIME 71 9081
TEST_TIME 72 9035
TEST_TIME 73 9009
TEST_TIME 74 8985
TEST_TIME 75 8936
TEST_TIME 76 8892
TEST_TIME 77 8835
TEST_TIME 78 8811
TEST_TIME 79 8870
TEST_TIME 80 8912
TEST_TIME 81 8879
TEST_TIME 82 8929
TEST_TIME 83 8969
TEST_TIME 84 9011
TEST_TIME 85 8999
TEST_TIME 86 8998
TEST_TIME 87 9049
TEST_TIME 88 9062
TEST_TIME 89 9063
TEST_TIME 90 9081
TEST_TIME 91 9002
TEST_TIME 92 8969
TEST_TIME 93 8968
TEST_TIME 94 8919
TEST_TIME 95 8914
TEST_TIME 96 8893
TEST_TIME 97 8901
TEST_TIME 98 8897
TEST_TIME 99 8946
TEST_TIME 100 8952
TEST_TIME 101 8955
TEST_TIME 102 8933
TEST_TIME 103 9020
TEST_TIME 104 8989
TEST_TIME 105 9011
TEST_TIME 106 9059
TEST_TIME 107 9040
TEST_TIME 108 9063
TEST_TIME 109 9037
TEST_TIME 110 8967
TEST_TIME 111 8908
TEST_TIME 112 8955
TEST_TIME 113 8914
TEST_TIME 114 8874
TEST_TIME 115 8873
TEST_TIME 116 8855
TEST_TIME 117 8833
TEST_TIME 118 8831
TEST_TIME 119 8897
TEST_TIME 120 8827

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-09-2021 10:38:14 to 18-09-2021 10:38:17.

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 1486823 1486823 0 0.00 0 36596 7 0 0
doc_list_biud 1486823 1486823 0 0.00 0 50567 34 0 0
sp_add_invoice_to_stock 5253 5099 154 2.93 36 212133 19230 0 154
sp_cancel_adding_invoice 5223 5223 0 0.00 7 108558 1010 0 0
sp_cancel_client_order 1725 1725 0 0.00 5 11984 335 0 0
sp_cancel_customer_reserve 511609 511609 0 0.00 1 30884 79 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 6594 99 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 2673 90 0 0
sp_cancel_supplier_invoice 5835 5818 17 0.29 2 15830 1037 0 17
sp_cancel_supplier_order 392 392 0 0.00 8 989494 127705 0 0
sp_cancel_write_off 8516 8516 0 0.00 1 7534 129 0 0
sp_client_order 20808 20808 0 0.00 4 53746 445 0 0
sp_customer_reserve 463542 463542 0 0.00 0 65233 191 0 0
sp_fill_shopping_cart 31871 31871 0 0.00 1 12331 46 0 0
sp_fill_shopping_cart_clo_res 463541 463541 0 0.00 0 18175 17 0 0
sp_get_clo_for_invoice 5098 5098 0 0.00 0 36531 407 0 0
sp_kill_cost_storno 14837 14837 0 0.00 0 36584 67 0 0
sp_kill_qstorno_ret_qs2qd 1037944 1037944 0 0.00 0 30690 42 0 0
sp_kill_qty_storno 548471 548471 0 0.00 0 50566 90 0 0
sp_lock_selected_doc 21010 21010 0 0.00 0 10868 6 0 0
sp_make_cost_storno 28089 28089 0 0.00 0 21617 55 0 0
sp_multiply_rows_for_pdistr 28089 28089 0 0.00 0 7752 32 0 0
sp_multiply_rows_for_qdistr 20808 20808 0 0.00 0 12900 222 0 0
sp_pay_from_customer 7036 7036 0 0.00 4 8877 114 0 0
sp_pay_to_supplier 5865 5865 0 0.00 5 21881 126 0 0
sp_payment_common 12901 12901 0 0.00 1 7732 19 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 11978 307 0 0
sp_qd_handle_on_invoice_upd_sts 10321 10321 0 0.00 0 50563 399 0 0
sp_qd_handle_on_reserve_upd_sts 10090 10090 0 0.00 0 8034 53 0 0
sp_reserve_write_off 10090 10090 0 0.00 6 10880 178 0 0
sp_supplier_invoice 5497 5497 0 0.00 7 99638 3881 0 0
sp_supplier_order 5574 5574 0 0.00 5 42458 1518 0 0
srv_aggregate_perf_data 114 114 0 0.00 2468 131648 16950 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1241 110706 42325 0 0
srv_find_qd_qs_mism 1010651 1010651 0 0.00 0 23483 6 0 0
srv_make_invnt_saldo 434 434 0 0.00 69 119714 3869 0 0
srv_make_money_saldo 754 754 0 0.00 1 15993 593 0 0
srv_recalc_idx_stat 5 5 0 0.00 162181 400323 276346 0 0
t$perf-abend:sp_add_invoice_to_stock 154 154 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5100 5100 0 0.00 0 9720 177 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 1 2946 100 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 2835 14 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 4593 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 6475 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 1190 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 11391 42 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 27988 1739 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 4359 14 0 0
t$perf-norm:sp_client_order 20808 20808 0 0.00 0 11549 24 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 4337 15 0 0
t$perf-norm:sp_pay_from_customer 7036 7036 0 0.00 0 2918 7 0 0
t$perf-norm:sp_pay_to_supplier 5865 5865 0 0.00 0 7439 15 0 0
t$perf-norm:sp_reserve_write_off 10090 10090 0 0.00 0 11412 13 0 0
t$perf-norm:sp_supplier_invoice 5497 5497 0 0.00 0 10307 19 0 0
t$perf-norm:sp_supplier_order 5574 5574 0 0.00 0 7623 19 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 141 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 51 2 0 0
t$perf-norm:srv_make_invnt_saldo 434 434 0 0.00 0 1064 11 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 1024 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 41 11 0 0
v_add_invoice_to_stock 10504 10196 308 2.93 0 6632 20 0 308
v_all_customers 37530 37530 0 0.00 0 74 1 0 0
v_all_suppliers 30146 30146 0 0.00 0 22 1 0 0
v_all_wares 41616 41616 0 0.00 0 46 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 457 7 0 0
v_cancel_client_order 3450 3450 0 0.00 0 2096 4 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 1299 5 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 786 4 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 1933 7 0 0
v_cancel_supplier_invoice 876 842 34 3.88 0 1117 18 0 34
v_cancel_supplier_order 784 784 0 0.00 0 1162 5 0 0
v_cancel_write_off 2060 2060 0 0.00 0 1374 14 0 0
v_max_id_clo_ord 5573 5573 0 0.00 0 2547 2 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 3193 12 0 0
v_max_id_ord_sup 5489 5489 0 0.00 0 4440 7 0 0
v_max_non_paid_invoice 1854 1854 0 0.00 0 331 2 0 0
v_max_non_paid_realizn 3811 3811 0 0.00 0 1208 3 0 0
v_min_id_clo_ord 5573 5573 0 0.00 0 1299 4 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 5566 14 0 0
v_min_id_ord_sup 5489 5489 0 0.00 0 4651 7 0 0
v_min_non_paid_invoice 5865 5865 0 0.00 0 5473 11 0 0
v_min_non_paid_realizn 7036 7036 0 0.00 0 7726 12 0 0
v_our_firm 10536 10536 0 0.00 0 21 1 0 0
v_reserve_write_off 20180 20180 0 0.00 0 2295 6 0 0
x_lock_depdocs_on_canc_invoice 10620 10620 0 0.00 0 15036 140 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 13421 425 0 0
x_make_qstorno_1000_1200 5574 5574 0 0.00 2 42449 1431 0 0
x_make_qstorno_1200_2000 5497 5497 0 0.00 4 99618 3709 0 0
x_make_qstorno_2100_3300 463542 463542 0 0.00 0 65232 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-09-2021 10:38:18 to 18-09-2021 10:38:22.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:09 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:09 35859 8192000000 9714463472 10012291072 795803920 8.854 100 90 180 0 826292048 8034960 22453168
08:09:52 7566 8192000000 9759242208 10065162240 834443200 9.244 100 90 180 0 869061776 13129280 21489296
08:11:02 16706 8192000000 9798176736 10112671744 860603152 9.506 100 86 172 0 904756256 24086448 20066656
08:12:30 9794 8192000000 9879366496 10213249024 925256304 10.148 100 84 168 0 991656816 48320400 18080112
08:13:43 6669 8192000000 9938569840 10284838912 962505808 10.514 100 83 166 0 1053489568 73613872 17369888
08:14:52 3918 8192000000 9968581440 10329714688 1004516768 10.922 100 86 172 1 1089884144 67137632 18229744
08:15:58 1867 8192000000 10004053568 10365927424 1032972288 11.197 100 88 175 1 1121590192 71089600 17528304
08:17:02 1299 8192000000 10040957184 10408112128 1059180752 11.449 100 94 188 1 1152653904 74006896 19466256
08:18:04 1016 8192000000 10025787472 10397802496 1067969920 11.533 100 86 175 0 1137534944 52344080 17220944
08:19:06 6876 8192000000 9996476640 10395152384 1074288192 11.593 100 48 96 0 1123014256 38956656 9769408
08:20:14 10714 8192000000 9995806256 10391359488 1082449104 11.671 100 27 54 0 1120852384 32431728 5971552
08:21:29 5568 8192000000 10005162496 10396442624 1087071264 11.715 100 51 102 2 1114420288 16895712 10453312
08:22:36 47064 8192000000 9995743456 10389467136 1090274720 11.745 100 40 80 1 1114247136 15175424 8796992
08:24:23 5686 8192000000 10000276544 10405523456 1094724448 11.788 100 24 48 0 1130759232 30735648 5299136
08:25:29 3246 8192000000 10012505728 10411159552 1099557808 11.833 100 53 102 6 1124047280 12815728 11673744
08:26:33 6126 8192000000 10018948416 10423136256 1102158848 11.858 100 29 58 1 1147501232 39449840 5892544
08:27:42 34702 8192000000 9973093408 10370215936 1038210976 11.247 95 35 69 1 1062041568 16104704 7725888
08:29:17 3207 8192000000 9969125248 10351558656 1027207568 11.142 98 66 132 1 1066661552 24924880 14529104
08:30:23 7807 8192000000 9929601600 10325524480 994643456 10.827 98 50 100 4 1030339216 23653424 12042336
08:31:35 9042 8192000000 9959941808 10348179456 1021162576 11.083 99 34 68 0 1051376880 23350640 6863664
08:32:52 21116 8192000000 9988386144 10398662656 1038729520 11.252 100 38 76 0 1083799456 37198704 7871232
08:34:15 5169 8192000000 9988522256 10371354624 1055919520 11.417 100 56 111 0 1100209856 31530352 12759984
08:35:21 7258 8192000000 9976371328 10377310208 1061026032 11.466 100 46 93 2 1088111968 16662112 10423824
08:36:29 8078 8192000000 9992542800 10398154752 1068530992 11.538 100 46 92 1 1105966064 27098736 10336336
08:37:41 66141 8192000000 10012857264 10408656896 1089135568 11.734 100 43 82 1 1113358592 15715472 8507552
08:40:08 18104 8192000000 10074718464 10470912000 1110331040 11.936 100 56 112 0 1181576448 59958832 11286576
08:41:31 21765 8192000000 10113412960 10521665536 1131204032 12.133 100 59 117 1 1218855792 74617184 13034576
08:42:58 1478 8192000000 10137882368 10536144896 1144601568 12.259 100 80 156 2 1243433600 82407280 16424752
08:44:01 13386 8192000000 10172403568 10580463616 1148638544 12.297 100 76 151 1 1281393264 116957440 15797280
08:45:18 7234 8192000000 10214160272 10624868352 1162548640 12.427 100 64 127 1 1320651296 144801056 13301600
08:46:30 2484 8192000000 10235032464 10640666624 1170375888 12.500 100 77 153 2 1334912576 147258208 17278480
08:47:36 3124 8192000000 10259970272 10665013248 1156994160 12.375 99 71 141 1 1358509392 186665568 14849664
08:48:42 3586 8192000000 10257289600 10673946624 1172299440 12.518 100 59 118 1 1364452624 178591872 13561312
08:49:50 4811 8192000000 10280381568 10684088320 1151885200 12.327 100 70 139 1 1381088896 213691968 15511728
08:50:58 3089 8192000000 10258518992 10678493184 1134069984 12.160 100 85 171 0 1360879712 208317872 18491856
08:52:04 1238 8192000000 10307830784 10705125376 1131721872 12.138 99 80 159 1 1401950880 252781520 17447488
08:53:11 2289 8192000000 10342045488 10749042688 1156863888 12.374 100 78 154 2 1446127712 271775168 17488656
08:54:18 2193 8192000000 10299593264 10728574976 1161343968 12.416 100 74 143 1 1396760464 219715872 15700624
08:55:25 1812 8192000000 10343447648 10742890496 1160112576 12.404 100 79 156 0 1443549344 265520752 17916016
08:56:32 4006 8192000000 10384856816 10792288256 1147469488 12.286 99 75 149 0 1473314224 308252320 17592416
08:57:45 4885 8192000000 10429944320 10844008448 1147249584 12.284 99 61 122 0 1519285088 357082992 14952512
08:58:54 7718 8192000000 10363805552 10778337280 1159391968 12.398 100 47 95 1 1460203408 289195552 11615888
09:00:06 13246 8192000000 10383912224 10842423296 1170842032 12.505 100 41 82 1 1488953664 307613744 10497888
09:01:22 5931 8192000000 10488200448 10910703616 1190189664 12.685 100 63 125 1 1578443680 372878784 15375232
09:02:36 17383 8192000000 10502486752 10994528256 1192882272 12.710 100 41 80 0 1636868208 432858736 11127200
09:04:01 18920 8192000000 10509621632 10978312192 1191124288 12.694 100 31 62 1 1612825216 413495184 8205744
09:05:26 18265 8192000000 10554390208 11040161792 1160142112 12.405 100 28 56 0 1678655232 510132832 8380288
09:06:46 44669 8192000000 10098068144 10660724736 1103608416 11.872 97 14 28 1 1400039520 292601056 3830048
09:08:39 75118 8192000000 10065040512 10502561792 1094047824 11.781 96 37 76 2 1126171856 23665280 8458752
09:11:46 56416 8192000000 10052541040 10505072640 1091894304 11.761 96 17 32 0 1136788256 41757232 3136720
09:13:47 49690 8192000000 10059946464 10520514560 1130238624 12.124 100 15 30 0 1161751552 28826304 2686624
09:15:41 74154 8192000000 10099851904 10545995776 1128743296 12.110 99 24 46 0 1174055904 40648336 4664272
09:17:58 97487 8192000000 10145469344 10609434624 1145368704 12.266 100 21 42 1 1240962432 90967648 4626080
09:20:54 41198 8192000000 10071210080 10510675968 1128473408 12.107 99 37 73 1 1155867520 19983056 7411056
09:22:46 47764 8192000000 10076703872 10533863424 1138025440 12.197 99 25 50 1 1164787120 21751776 5009904
09:24:41 78363 8192000000 10118179760 10569854976 1155338624 12.360 100 12 25 0 1202999872 45230912 2430336
09:27:18 93135 8192000000 10078345024 10584645632 1151205152 12.321 100 22 44 0 1228563392 72355488 5002752
09:29:53 31986 8192000000 10114199424 10557771776 1152258112 12.331 100 39 78 2 1220082848 59246640 8578096
09:31:29 75191 8192000000 10086944240 10573783040 1154861248 12.355 100 21 42 0 1195847120 36632240 4353632
09:33:46 25512 8192000000 10067820384 10525995008 1136355952 12.181 99 27 54 2 1165302160 23594304 5351904
09:35:19 76179 8192000000 10073841264 10531295232 1100208144 11.840 96 18 36 0 1153688368 49417872 4062352
09:37:36 61098 8192000000 10093870656 10544627712 1127782448 12.100 98 19 38 0 1229552000 97539280 4230272
09:39:46 49596 8192000000 10102625072 10567909376 1078431360 11.633 94 10 19 0 1159889856 79150912 2307584
09:41:36 48220 8192000000 10084802448 10551533568 1091715920 11.759 96 12 24 0 1139348112 45611104 2021088
09:43:25 11674 8192000000 10041217472 10455064576 1092779520 11.769 98 47 94 2 1127263088 25188368 9295200
09:44:39 72121 8192000000 10048635856 10494173184 1095338272 11.793 97 15 30 1 1128057568 29582480 3136816
09:46:51 110706 8192000000 10035115280 10478096384 1092016480 11.762 97 31 61 1 1144785184 46100704 6668000
09:49:47 30134 8192000000 10062938320 10506797056 1129674688 12.118 100 35 69 1 1191548224 54417648 7455888
09:51:21 57499 8192000000 10043833200 10514169856 1096400000 11.803 98 11 23 1 1143940672 45176080 2364592
09:53:19 75269 8192000000 10085820672 10543644672 1112625552 11.957 98 18 36 0 1166851248 50167856 4057840
09:55:35 44629 8192000000 10088292592 10569678848 1107519520 11.909 98 12 24 0 1207274192 96824208 2930464
09:57:23 39113 8192000000 10063746528 10513719296 1118057600 12.009 98 15 30 0 1158480400 37399808 3022992
09:59:04 46822 8192000000 10078069792 10514718720 1122315840 12.049 99 18 36 0 1188994416 62713216 3965360
10:00:54 56050 8192000000 10108195024 10557931520 1120864096 12.035 99 28 56 1 1179962560 53202048 5896416
10:02:51 24392 8192000000 10090311904 10554470400 1113444640 11.965 99 33 66 0 1175169344 55120896 6603808
10:04:24 63803 8192000000 10067991120 10511679488 1103238528 11.868 97 15 30 0 1145478448 39676960 2562960
10:06:29 99160 8192000000 10051945072 10490855424 1081794576 11.665 96 19 38 0 1157236448 71863024 3578848
10:09:28 61930 8192000000 10048889328 10541228032 1091870528 11.760 97 21 42 2 1172228688 75804096 4554064
10:11:59 23298 8192000000 10050817728 10537254912 1109480032 11.927 99 3 6 0 1152769120 42942752 346336
10:13:25 60944 8192000000 10134396240 10593333248 1136246144 12.180 99 19 39 0 1191401200 51607824 3547232
10:15:30 95562 8192000000 10105847952 10606346240 1117568528 12.004 97 20 40 1 1211208704 89082512 4557664
10:18:22 51632 8192000000 10122356096 10571153408 1137968352 12.196 99 25 50 1 1213013456 70159184 4885920
10:20:41 93948 8192000000 10083187632 10557890560 1141514496 12.230 99 14 28 0 1182601696 38180848 2906352
10:23:15 66377 8192000000 10082409888 10559270912 1110864800 11.941 96 18 36 0 1160057632 45555680 3637152
10:25:26 48246 8192000000 10114685520 10553171968 1120659296 12.033 98 25 49 0 1180981840 55654016 4668528
10:27:18 79642 8192000000 10137493584 10577690624 1134780112 12.166 100 41 82 0 1240085504 95752800 9552592
10:29:40 43343 8192000000 10085175504 10561937408 1125780224 12.082 99 9 18 0 1197042384 69037136 2225024
10:31:30 51353 8192000000 10066209536 10567974912 1138547888 12.202 100 5 10 0 1208306064 68582224 1175952
10:33:30 55515 8192000000 10087048256 10535677952 1086741888 11.712 96 20 39 1 1128057888 37384176 3931824
10:35:34 61327 8192000000 10106150880 10574258176 1104834016 11.883 98 21 41 1 1190683072 81516640 4332416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-09-2021 10:38:24 to 18-09-2021 10:38:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 154 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-09-2021 10:38:40 to 18-09-2021 10:38:42.

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          237844
MON$OLDEST_ACTIVE               239707
MON$OLDEST_SNAPSHOT             239707
MON$NEXT_TRANSACTION            239707
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                       2065760
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             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2604 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2604 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2604 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2604 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 ---
:63: 
:64: mscs-test-161.budzdorov.ru    Sat Sep 18 10:37:16 2021
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Sat Sep 18 10:37:16 2021
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:70: 
--- end of diff output ---

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

18.09.21 10:38:43. 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-09-18 10:38:46 No severe PSQL-related problems occured
18.09.21 10:38:47. End of report.