Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.10.2021 04:39
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 16053928 kB
MemAvailable 30738160 kB
Buffers 20 kB
Cached 15627996 kB
SwapCached 4684 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-10-03 04:37:08
DTS_END                         2021-10-03 04:37:08.6950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35650
TRN_ID                          237085
ATT_ID                          653
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: 8855.11 18000 1062613
customer order: creation 176.27 406 21152
customer order: refuse 15.31 378 1837
order to supplier: creation 48.27 1458 5792
order to supplier: removal 3.27 113951 392
invoice (draft): creation 46.83 3540 5620
invoice (draft): removal 48.41 1017 5809
invoice accept: apply 42.55 18400 5106
invoice accept: cancel 43.16 785 5179
customer reserve: creation 3869.23 184 464308
customer reserve: removal 4262.58 68 511510
realization accept: apply 86.14 204 10337
realization accept: cancel 82.55 127 9906
payment from customer: creation 59.38 119 7126
payment from customer: removal 5.62 66 674
payment to supplier: creation 50.23 131 6027
payment to supplier: removal 3.77 70 452
service: total inventory turnovers 4.03 3523 484
service: total monetary turnovers 6.44 538 773
service: aggregate perf. data 1.04 13530 125
service: refresh index statistics 0.03 201498 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-10-2021 04:39:31 to 03-10-2021 04:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6965
TEST_TIME 2 6315
TEST_TIME 3 5846
TEST_TIME 4 5470
TEST_TIME 5 5062
TEST_TIME 6 5053
TEST_TIME 7 5560
TEST_TIME 8 5655
TEST_TIME 9 5796
TEST_TIME 10 6049
TEST_TIME 11 6072
TEST_TIME 12 6194
TEST_TIME 13 6279
TEST_TIME 14 6393
TEST_TIME 15 6545
TEST_TIME 16 6783
TEST_TIME 17 7103
TEST_TIME 18 7676
TEST_TIME 19 8154
TEST_TIME 20 8313
TEST_TIME 21 8592
TEST_TIME 22 8461
TEST_TIME 23 9028
TEST_TIME 24 9400
TEST_TIME 25 9992
TEST_TIME 26 10916
TEST_TIME 27 10717
TEST_TIME 28 10511
TEST_TIME 29 10275
TEST_TIME 30 10156
TEST_TIME 31 10092
TEST_TIME 32 9987
TEST_TIME 33 9875
TEST_TIME 34 9733
TEST_TIME 35 9591
TEST_TIME 36 9521
TEST_TIME 37 9402
TEST_TIME 38 9193
TEST_TIME 39 9095
TEST_TIME 40 9317
TEST_TIME 41 9385
TEST_TIME 42 9252
TEST_TIME 43 9243
TEST_TIME 44 9180
TEST_TIME 45 9230
TEST_TIME 46 9302
TEST_TIME 47 9203
TEST_TIME 48 9192
TEST_TIME 49 9197
TEST_TIME 50 9421
TEST_TIME 51 9380
TEST_TIME 52 9245
TEST_TIME 53 9197
TEST_TIME 54 9107
TEST_TIME 55 9049
TEST_TIME 56 9103
TEST_TIME 57 9019
TEST_TIME 58 8920
TEST_TIME 59 8816
TEST_TIME 60 9070
TEST_TIME 61 9022
TEST_TIME 62 9036
TEST_TIME 63 8990
TEST_TIME 64 9031
TEST_TIME 65 9011
TEST_TIME 66 8998
TEST_TIME 67 8957
TEST_TIME 68 8994
TEST_TIME 69 9036
TEST_TIME 70 8984
TEST_TIME 71 9051
TEST_TIME 72 9118
TEST_TIME 73 9142
TEST_TIME 74 9178
TEST_TIME 75 9083
TEST_TIME 76 9084
TEST_TIME 77 9057
TEST_TIME 78 9079
TEST_TIME 79 9027
TEST_TIME 80 9003
TEST_TIME 81 8974
TEST_TIME 82 8977
TEST_TIME 83 8938
TEST_TIME 84 8918
TEST_TIME 85 8880
TEST_TIME 86 8858
TEST_TIME 87 8890
TEST_TIME 88 9027
TEST_TIME 89 9001
TEST_TIME 90 8982
TEST_TIME 91 9009
TEST_TIME 92 8982
TEST_TIME 93 9076
TEST_TIME 94 9110
TEST_TIME 95 9131
TEST_TIME 96 9196
TEST_TIME 97 9155
TEST_TIME 98 9109
TEST_TIME 99 9056
TEST_TIME 100 9052
TEST_TIME 101 9004
TEST_TIME 102 8968
TEST_TIME 103 8919
TEST_TIME 104 8919
TEST_TIME 105 8907
TEST_TIME 106 8968
TEST_TIME 107 8961
TEST_TIME 108 8938
TEST_TIME 109 8952
TEST_TIME 110 8964
TEST_TIME 111 8990
TEST_TIME 112 8956
TEST_TIME 113 9046
TEST_TIME 114 9052
TEST_TIME 115 9052
TEST_TIME 116 9038
TEST_TIME 117 8997
TEST_TIME 118 8937
TEST_TIME 119 8928
TEST_TIME 120 8858

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-10-2021 04:39:34 to 03-10-2021 04:39:38.

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 1491712 1491712 0 0.00 0 24004 7 0 0
doc_list_biud 1491710 1491710 0 0.00 0 54741 30 0 0
sp_add_invoice_to_stock 5247 5106 141 2.68 38 166420 18401 0 141
sp_cancel_adding_invoice 5179 5179 0 0.00 6 62717 786 0 0
sp_cancel_client_order 1837 1837 0 0.00 4 13024 378 0 0
sp_cancel_customer_reserve 511510 511510 0 0.00 1 54750 68 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 2438 67 0 0
sp_cancel_pay_to_supplier 452 452 0 0.00 3 1997 71 0 0
sp_cancel_supplier_invoice 5819 5809 10 0.17 3 17571 1018 0 10
sp_cancel_supplier_order 392 392 0 0.00 11 1309716 113951 0 0
sp_cancel_write_off 9906 9906 0 0.00 1 15523 128 0 0
sp_client_order 21152 21152 0 0.00 4 26297 406 0 0
sp_customer_reserve 464308 464308 0 0.00 0 110380 185 0 0
sp_fill_shopping_cart 32538 32538 0 0.00 1 13696 47 0 0
sp_fill_shopping_cart_clo_res 464301 464301 0 0.00 0 17674 17 0 0
sp_get_clo_for_invoice 5099 5099 0 0.00 0 20414 357 0 0
sp_kill_cost_storno 16211 16211 0 0.00 0 10026 54 0 0
sp_kill_qstorno_ret_qs2qd 1039127 1039127 0 0.00 0 54670 38 0 0
sp_kill_qty_storno 550069 550069 0 0.00 0 54740 80 0 0
sp_lock_selected_doc 21255 21255 0 0.00 0 7115 5 0 0
sp_make_cost_storno 28589 28589 0 0.00 0 24001 58 0 0
sp_multiply_rows_for_pdistr 28589 28589 0 0.00 0 9295 36 0 0
sp_multiply_rows_for_qdistr 21152 21152 0 0.00 0 15585 199 0 0
sp_pay_from_customer 7126 7126 0 0.00 4 11283 119 0 0
sp_pay_to_supplier 6027 6027 0 0.00 5 12037 132 0 0
sp_payment_common 13153 13153 0 0.00 1 10196 24 0 0
sp_qd_handle_on_cancel_clo 1837 1837 0 0.00 0 13017 355 0 0
sp_qd_handle_on_invoice_upd_sts 10278 10278 0 0.00 0 25395 322 0 0
sp_qd_handle_on_reserve_upd_sts 10337 10337 0 0.00 0 22885 65 0 0
sp_reserve_write_off 10337 10337 0 0.00 6 24301 205 0 0
sp_supplier_invoice 5620 5620 0 0.00 6 84899 3541 0 0
sp_supplier_order 5792 5792 0 0.00 6 75838 1459 0 0
srv_aggregate_perf_data 125 125 0 0.00 2809 154632 13530 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2059 117641 45597 0 0
srv_find_qd_qs_mism 1013764 1013764 0 0.00 0 17601 6 0 0
srv_make_invnt_saldo 484 484 0 0.00 58 159200 3524 0 0
srv_make_money_saldo 773 773 0 0.00 1 14441 538 0 0
srv_recalc_idx_stat 4 4 0 0.00 103555 264829 201498 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 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 5106 5106 0 0.00 0 23631 156 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 16372 111 0 0
t$perf-norm:sp_cancel_client_order 1837 1837 0 0.00 0 1805 10 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 9391 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 6153 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 452 452 0 0.00 0 4525 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 4416 23 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 37292 1534 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 5150 10 0 0
t$perf-norm:sp_client_order 21152 21152 0 0.00 0 18403 18 0 0
t$perf-norm:sp_customer_reserve 1050 1050 0 0.00 0 3194 7 0 0
t$perf-norm:sp_pay_from_customer 7126 7126 0 0.00 0 13176 14 0 0
t$perf-norm:sp_pay_to_supplier 6027 6027 0 0.00 0 29672 16 0 0
t$perf-norm:sp_reserve_write_off 10337 10337 0 0.00 0 6282 10 0 0
t$perf-norm:sp_supplier_invoice 5620 5620 0 0.00 0 15436 18 0 0
t$perf-norm:sp_supplier_order 5792 5792 0 0.00 0 4596 14 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 594 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 27 1 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 762 9 0 0
t$perf-norm:srv_make_money_saldo 773 773 0 0.00 0 1829 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10480 10198 282 2.69 0 9216 18 0 282
v_all_customers 38046 38046 0 0.00 0 143 1 0 0
v_all_suppliers 31512 31512 0 0.00 0 78 1 0 0
v_all_wares 42304 42304 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 4651 13 0 0
v_cancel_client_order 3674 3674 0 0.00 0 2723 6 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 2417 5 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 232 3 0 0
v_cancel_payment_to_supplier 904 904 0 0.00 0 216 4 0 0
v_cancel_supplier_invoice 760 740 20 2.63 0 2611 16 0 20
v_cancel_supplier_order 784 784 0 0.00 0 2748 7 0 0
v_cancel_write_off 1980 1980 0 0.00 0 3922 16 0 0
v_max_id_clo_ord 5782 5782 0 0.00 0 1857 1 0 0
v_max_id_clo_res 1050 1050 0 0.00 0 2336 16 0 0
v_max_id_ord_sup 5604 5604 0 0.00 0 3501 5 0 0
v_max_non_paid_invoice 1657 1657 0 0.00 0 1598 4 0 0
v_max_non_paid_realizn 3916 3916 0 0.00 0 3011 4 0 0
v_min_id_clo_ord 5782 5782 0 0.00 0 10340 12 0 0
v_min_id_clo_res 1050 1050 0 0.00 0 3453 10 0 0
v_min_id_ord_sup 5604 5604 0 0.00 0 8436 15 0 0
v_min_non_paid_invoice 6027 6027 0 0.00 0 8593 17 0 0
v_min_non_paid_realizn 7126 7126 0 0.00 0 6606 15 0 0
v_our_firm 10678 10678 0 0.00 0 73 1 0 0
v_reserve_write_off 20674 20674 0 0.00 0 9243 9 0 0
x_lock_depdocs_on_canc_invoice 10618 10618 0 0.00 0 10775 110 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 8987 327 0 0
x_make_qstorno_1000_1200 5792 5792 0 0.00 2 75821 1378 0 0
x_make_qstorno_1200_2000 5620 5620 0 0.00 3 84894 3352 0 0
x_make_qstorno_2100_3300 464308 464308 0 0.00 0 110354 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-10-2021 04:39:39 to 03-10-2021 04:39:43.

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
02:07:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:13 26006 8192000000 9723208592 10021761024 802716000 8.924 100 87 174 1 831766656 7650320 21400336
02:09:41 20632 8192000000 9760016896 10067353600 838713712 9.287 100 91 182 0 873434048 12997872 21722464
02:11:09 4389 8192000000 9797385984 10115461120 865261552 9.553 100 82 164 0 910972816 26773232 18938032
02:12:19 11525 8192000000 9864399824 10196480000 913382272 10.031 100 83 166 0 972463680 40473296 18608112
02:13:36 6496 8192000000 9912904016 10259382272 962164448 10.510 100 77 154 0 1023789232 44610400 17014384
02:14:45 11136 8192000000 9971420704 10328395776 996837136 10.848 100 75 150 0 1079409712 66799584 15772992
02:15:58 10232 8192000000 9985893680 10351063040 1018835952 11.061 100 72 141 0 1098291440 65791792 13663696
02:17:11 2609 8192000000 10011441424 10377105408 1036907680 11.235 100 87 174 0 1131585920 77217376 17460864
02:18:15 2414 8192000000 10036840416 10405412864 1058321696 11.440 100 90 183 2 1141726832 64826608 18578528
02:19:19 1269 8192000000 10015652560 10387353600 1064779424 11.502 100 82 162 1 1117948256 36531808 16637024
02:20:21 3208 8192000000 9991476112 10384470016 1067044704 11.524 100 45 89 1 1099143408 23164112 8934592
02:21:26 5270 8192000000 10010857648 10407297024 1084747728 11.693 100 48 97 0 1128819200 33157872 10913600
02:22:34 9440 8192000000 10003850112 10407497728 1088738624 11.731 100 33 67 1 1120392784 24205552 7448608
02:23:47 3174 8192000000 10030523488 10426359808 1093301248 11.774 100 50 100 0 1145454576 41570944 10582384
02:24:52 5155 8192000000 10015010192 10409242624 1098230480 11.821 100 42 80 1 1130336656 22524992 9581184
02:25:58 63630 8192000000 10004577312 10422259712 1078551200 11.634 98 30 60 1 1103256624 18303120 6402304
02:28:05 5226 8192000000 9998534752 10401398784 1097355664 11.813 100 38 74 1 1118124704 11877488 8891552
02:29:13 9018 8192000000 9967810224 10366160896 1039343440 11.258 98 28 56 2 1075073952 30211200 5519312
02:30:23 14266 8192000000 9927321328 10322939904 1005787904 10.935 97 28 55 2 1034519376 23102064 5629408
02:31:45 8852 8192000000 9931787760 10318585856 990013248 10.782 96 19 38 0 1022722240 28545376 4163616
02:32:58 7220 8192000000 9961448816 10340679680 1011060800 10.986 98 49 97 0 1037635856 15825360 10749696
02:34:06 6585 8192000000 9965383088 10352119808 1046952768 11.331 100 56 113 1 1077595216 17251888 13390560
02:35:15 35628 8192000000 9982611600 10377609216 1062601040 11.481 100 46 92 2 1098877856 25737744 10539072
02:36:52 5488 8192000000 9980772880 10384891904 1074064880 11.591 100 44 89 0 1093932624 9013936 10853808
02:38:09 5379 8192000000 10037203168 10430959616 1101824160 11.855 100 59 119 1 1147624256 33761696 12038400
02:39:19 10522 8192000000 10088099376 10490888192 1114591872 11.976 100 59 116 1 1203815728 77269088 11954768
02:40:34 67648 8192000000 10093221056 10517417984 1128693376 12.109 100 37 75 0 1214814032 78664992 7455664
02:42:53 8715 8192000000 10154280496 10567143424 1144111824 12.254 100 47 94 2 1271456256 116594400 10750032
02:44:07 20120 8192000000 10172908336 10610425856 1151763552 12.326 100 46 92 1 1273271344 111408560 10099232
02:45:39 4380 8192000000 10230596960 10637746176 1166104320 12.460 100 76 150 0 1331675152 149185072 16385760
02:46:46 3955 8192000000 10221074400 10632826880 1161469920 12.417 100 59 117 0 1318017712 143183072 13364720
02:47:52 4810 8192000000 10214851008 10649026560 1181898128 12.608 100 47 93 0 1325506112 133300368 10307616
02:49:01 9143 8192000000 10294825744 10709360640 1192625776 12.708 100 73 145 0 1402932624 194459424 15847424
02:50:19 6075 8192000000 10324195664 10734944256 1169673296 12.494 98 79 157 1 1412720272 225553248 17493728
02:51:30 2056 8192000000 10359914112 10767306752 1187685360 12.662 100 82 160 2 1459360288 252693104 18981824
02:52:38 3532 8192000000 10420498736 10839937024 1189917456 12.683 99 88 174 3 1519873680 309376768 20579456
02:53:50 11357 8192000000 10453134848 10873688064 1189711312 12.681 100 71 141 0 1551594240 344777312 17105616
02:55:05 19268 8192000000 10401743792 10870710272 1158695312 12.391 99 45 90 1 1537391632 366407600 12288720
02:56:27 5261 8192000000 10392911056 10822406144 1138245056 12.199 98 48 96 2 1484442784 334408640 11789088
02:57:36 8488 8192000000 10402280544 10825191424 1128925760 12.111 98 51 101 0 1478333344 336745344 12662240
02:58:47 12581 8192000000 10377883552 10834141184 1131212448 12.133 99 41 82 0 1458803472 317017120 10573904
03:00:17 13708 8192000000 10410518304 10841714688 1128198624 12.104 99 33 65 2 1506900160 369897520 8804016
03:01:35 27180 8192000000 10331605888 10798284800 1095695008 11.797 97 25 50 1 1440347840 337259888 7392944
03:03:04 21293 8192000000 10025531024 10494533632 1065708688 11.511 96 13 26 0 1138378464 69797456 2872320
03:04:27 12227 8192000000 10043987584 10459865088 1088934160 11.733 99 51 101 1 1144969264 45232960 10802144
03:05:45 95633 8192000000 10073982400 10502066176 1112248976 11.954 100 46 90 1 1161704432 39200832 10254624
03:08:21 84606 8192000000 10052037632 10486894592 1071138688 11.563 95 27 53 1 1093108576 17305968 4663920
03:11:53 46527 8192000000 10071365568 10510512128 1102210240 11.859 98 20 40 0 1162321664 55939184 4172240
03:13:45 48902 8192000000 10102367024 10546712576 1135587184 12.174 100 13 26 0 1213756272 75457040 2712048
03:16:09 26567 8192000000 10118597712 10574807040 1118159520 12.010 99 18 36 1 1213366544 91012416 4194608
03:17:36 63829 8192000000 10051988432 10532270080 1119486992 12.022 99 10 20 0 1176122704 54616864 2018848
03:19:40 32390 8192000000 10074728080 10520924160 1135617328 12.174 100 41 80 1 1174774544 30929072 8228144
03:21:22 61026 8192000000 10085468464 10555129856 1140588256 12.221 100 23 46 0 1198386000 53016064 4781680
03:23:25 57327 8192000000 10103916528 10548170752 1136454016 12.182 99 21 42 0 1183357312 42600336 4302960
03:25:27 101423 8192000000 10128577568 10616623104 1132013632 12.140 99 13 26 0 1235655744 100258848 3383264
03:28:10 53833 8192000000 10108885264 10550898688 1146200304 12.274 99 19 38 1 1202176192 51486960 4488928
03:30:05 86139 8192000000 10104088960 10547310592 1130234896 12.124 98 38 76 2 1196937600 57954928 8747776
03:32:34 54661 8192000000 10114491632 10608930816 1156975584 12.375 100 21 42 0 1250545232 88737760 4831888
03:35:52 51241 8192000000 10187459376 10647146496 1169215280 12.489 100 16 32 1 1284976704 111986992 3774432
03:38:39 33795 8192000000 10115218224 10566430720 1150396800 12.313 99 18 36 0 1213045232 59318112 3330320
03:40:17 65276 8192000000 10082246928 10542358528 1091000096 11.752 95 19 38 0 1148855840 53520576 4335168
03:42:23 73510 8192000000 10075005792 10518519808 1105398944 11.889 97 9 18 0 1137957280 30426448 2131888
03:44:40 87750 8192000000 10075713056 10509283328 1075132112 11.601 94 21 42 0 1151638512 72207440 4298960
03:47:08 33584 8192000000 10078873552 10504708096 1066343104 11.517 95 40 80 2 1113586736 38996624 8247008
03:48:47 80727 8192000000 10043699216 10520690688 1071402032 11.565 96 13 26 0 1109564768 35688976 2473760
03:51:08 114463 8192000000 10066958416 10515083264 1063896144 11.494 95 25 51 1 1138212432 68699984 5616304
03:54:05 33815 8192000000 10085748240 10519187456 1120720624 12.034 100 52 104 1 1188330800 56870528 10739648
03:55:42 44880 8192000000 10026578400 10467205120 1072048032 11.572 96 17 34 1 1108358544 32781664 3528848
03:57:29 67879 8192000000 10083422400 10532835328 1117560736 12.004 100 20 40 1 1159633296 37912672 4159888
03:59:37 46907 8192000000 10047651200 10505248768 1118085056 12.009 100 11 22 0 1150566560 30378736 2102768
04:01:24 69243 8192000000 10084854208 10533232640 1106042480 11.895 98 21 40 0 1139756288 29116048 4597760
04:03:34 72220 8192000000 10106518352 10567610368 1083862480 11.684 96 23 46 0 1179647344 90570416 5214448
04:05:46 46176 8192000000 10032716144 10466963456 1087843472 11.722 98 13 26 1 1114664272 24223088 2597712
04:07:33 34554 8192000000 10142723616 10581864448 1122689472 12.052 99 50 100 2 1202876896 70354112 9833312
04:09:07 27328 8192000000 10125960384 10555428864 1135286816 12.171 100 45 89 3 1202324192 57545792 9491584
04:10:39 72148 8192000000 10110343264 10566451200 1128127296 12.104 99 31 62 0 1189925984 55755856 6042832
04:12:56 117640 8192000000 10103423920 10603393024 1140002784 12.216 99 11 22 0 1184764192 42146192 2615216
04:16:20 40807 8192000000 10091138208 10555985920 1144792368 12.261 99 27 54 0 1189891264 39227216 5871680
04:18:01 51849 8192000000 10081524592 10551119872 1155149264 12.358 100 11 23 0 1192887184 35238480 2499440
04:19:59 54152 8192000000 10123944016 10576203776 1157274864 12.378 100 17 34 1 1213480400 52767696 3437840
04:21:53 65605 8192000000 10156748208 10665086976 1127894272 12.102 97 12 24 0 1242565968 112273584 2398112
04:23:59 51046 8192000000 10074954128 10521296896 1131729408 12.138 98 11 22 0 1155423136 21362832 2330896
04:26:03 54874 8192000000 10108056368 10559692800 1111374368 11.945 96 20 40 0 1161865008 46059824 4430816
04:28:10 67473 8192000000 10091241824 10523467776 1110382016 11.936 97 21 42 0 1163541136 48834960 4324160
04:30:17 80728 8192000000 10079413632 10505940992 1074677584 11.597 94 20 40 1 1110748960 31958976 4112400
04:32:38 32716 8192000000 10062859936 10511187968 1102609248 11.862 98 16 32 1 1134737104 29079232 3048624
04:34:16 109615 8192000000 10047211696 10483982336 1080660432 11.654 96 26 52 1 1116485888 30866240 4959216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 03-10-2021 04:39:45 to 03-10-2021 04:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 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 03-10-2021 04:40:00 to 03-10-2021 04:40:02.

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          235025
MON$OLDEST_ACTIVE               237194
MON$OLDEST_SNAPSHOT             237194
MON$NEXT_TRANSACTION            237194
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                       2022976
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             806
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2621 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2621 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2621 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2621 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 ---
:791: 
:792: mscs-test-161.budzdorov.ru    Sun Oct  3 04:37:30 2021
:793:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:794: 
:795: 
:796: mscs-test-161.budzdorov.ru    Sun Oct  3 04:37:30 2021
:797:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:798: 
:799: 
:800: mscs-test-161.budzdorov.ru    Sun Oct  3 04:37:30 2021
:801:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:802: 
:803: 
:804: mscs-test-161.budzdorov.ru    Sun Oct  3 04:37:30 2021
:805:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:806: 
:807: 
:808: mscs-test-161.budzdorov.ru    Sun Oct  3 04:37:30 2021
:809:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:810: 
:811: 
:812: mscs-test-161.budzdorov.ru    Sun Oct  3 04:37:30 2021
:813:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:814: 
:815: 
:816: mscs-test-161.budzdorov.ru    Sun Oct  3 04:37:31 2021
:817:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:818: 
:819: 
:820: mscs-test-161.budzdorov.ru    Sun Oct  3 04:37:31 2021
:821:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:822: 
--- end of diff output ---

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

03.10.21 04:40:03. 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-10-03 04:40:06 No severe PSQL-related problems occured
03.10.21 04:40:07. End of report.