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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16522408 kB
MemAvailable 31536620 kB
Buffers 0 kB
Cached 15200248 kB
SwapCached 0 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-12-07 10:37:10
DTS_END                         2021-12-07 10:37:10.3140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34868
TRN_ID                          227929
ATT_ID                          601
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: 8737.57 23992 1048508
customer order: creation 165.78 449 19894
customer order: refuse 14.14 413 1697
order to supplier: creation 44.80 1596 5376
order to supplier: removal 3.03 138858 363
invoice (draft): creation 44.55 4379 5346
invoice (draft): removal 48.71 1114 5845
invoice accept: apply 41.43 21252 4972
invoice accept: cancel 44.11 1149 5293
customer reserve: creation 3725.73 213 447087
customer reserve: removal 4324.45 75 518934
realization accept: apply 82.51 229 9901
realization accept: cancel 73.60 144 8832
payment from customer: creation 56.83 143 6820
payment from customer: removal 5.72 108 686
payment to supplier: creation 48.52 157 5822
payment to supplier: removal 3.23 114 388
service: total inventory turnovers 3.58 4453 430
service: total monetary turnovers 6.05 707 726
service: aggregate perf. data 0.76 21420 91
service: refresh index statistics 0.04 282869 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-12-2021 10:39:02 to 07-12-2021 10:39:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6061
TEST_TIME 2 3736
TEST_TIME 3 3960
TEST_TIME 4 4219
TEST_TIME 5 4609
TEST_TIME 6 5170
TEST_TIME 7 5295
TEST_TIME 8 5144
TEST_TIME 9 5491
TEST_TIME 10 5873
TEST_TIME 11 6032
TEST_TIME 12 5987
TEST_TIME 13 6031
TEST_TIME 14 6503
TEST_TIME 15 6610
TEST_TIME 16 6887
TEST_TIME 17 7137
TEST_TIME 18 7320
TEST_TIME 19 7524
TEST_TIME 20 7602
TEST_TIME 21 7673
TEST_TIME 22 8031
TEST_TIME 23 8276
TEST_TIME 24 8244
TEST_TIME 25 8553
TEST_TIME 26 8621
TEST_TIME 27 8592
TEST_TIME 28 8671
TEST_TIME 29 8906
TEST_TIME 30 9496
TEST_TIME 31 9926
TEST_TIME 32 10498
TEST_TIME 33 10512
TEST_TIME 34 10547
TEST_TIME 35 10405
TEST_TIME 36 10205
TEST_TIME 37 10193
TEST_TIME 38 10037
TEST_TIME 39 9868
TEST_TIME 40 10020
TEST_TIME 41 9857
TEST_TIME 42 9804
TEST_TIME 43 9765
TEST_TIME 44 9762
TEST_TIME 45 9656
TEST_TIME 46 9594
TEST_TIME 47 9548
TEST_TIME 48 9427
TEST_TIME 49 9357
TEST_TIME 50 9367
TEST_TIME 51 9281
TEST_TIME 52 9170
TEST_TIME 53 9145
TEST_TIME 54 9088
TEST_TIME 55 9049
TEST_TIME 56 8992
TEST_TIME 57 9113
TEST_TIME 58 9063
TEST_TIME 59 9112
TEST_TIME 60 9078
TEST_TIME 61 9109
TEST_TIME 62 9144
TEST_TIME 63 9076
TEST_TIME 64 9133
TEST_TIME 65 9141
TEST_TIME 66 9127
TEST_TIME 67 9180
TEST_TIME 68 9144
TEST_TIME 69 9117
TEST_TIME 70 8998
TEST_TIME 71 9071
TEST_TIME 72 9092
TEST_TIME 73 9032
TEST_TIME 74 8976
TEST_TIME 75 8910
TEST_TIME 76 8858
TEST_TIME 77 8919
TEST_TIME 78 8862
TEST_TIME 79 8813
TEST_TIME 80 8839
TEST_TIME 81 8803
TEST_TIME 82 8926
TEST_TIME 83 8935
TEST_TIME 84 8960
TEST_TIME 85 8950
TEST_TIME 86 9015
TEST_TIME 87 8965
TEST_TIME 88 9028
TEST_TIME 89 9017
TEST_TIME 90 8996
TEST_TIME 91 8919
TEST_TIME 92 8879
TEST_TIME 93 8825
TEST_TIME 94 8947
TEST_TIME 95 8887
TEST_TIME 96 8847
TEST_TIME 97 8837
TEST_TIME 98 8813
TEST_TIME 99 8835
TEST_TIME 100 8853
TEST_TIME 101 8874
TEST_TIME 102 8850
TEST_TIME 103 8855
TEST_TIME 104 8845
TEST_TIME 105 8848
TEST_TIME 106 8951
TEST_TIME 107 8975
TEST_TIME 108 9002
TEST_TIME 109 8987
TEST_TIME 110 8969
TEST_TIME 111 8996
TEST_TIME 112 8928
TEST_TIME 113 8899
TEST_TIME 114 8870
TEST_TIME 115 8888
TEST_TIME 116 8847
TEST_TIME 117 8813
TEST_TIME 118 8800
TEST_TIME 119 8785
TEST_TIME 120 8739

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-12-2021 10:39:06 to 07-12-2021 10:39:09.

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 1461433 1461433 0 0.00 0 25943 8 0 0
doc_list_biud 1461433 1461433 0 0.00 0 83450 35 0 0
sp_add_invoice_to_stock 5141 4972 169 3.28 63 196782 21253 0 169
sp_cancel_adding_invoice 5293 5293 0 0.00 6 66833 1150 0 0
sp_cancel_client_order 1697 1697 0 0.00 5 13619 413 0 0
sp_cancel_customer_reserve 518934 518934 0 0.00 1 28749 75 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 8647 108 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 5249 114 0 0
sp_cancel_supplier_invoice 5858 5845 13 0.22 2 29206 1114 0 13
sp_cancel_supplier_order 363 363 0 0.00 11 1076866 138858 0 0
sp_cancel_write_off 8832 8832 0 0.00 1 16447 145 0 0
sp_client_order 19894 19894 0 0.00 4 26004 450 0 0
sp_customer_reserve 447087 447087 0 0.00 0 70986 214 0 0
sp_fill_shopping_cart 30613 30613 0 0.00 1 30411 47 0 0
sp_fill_shopping_cart_clo_res 447085 447085 0 0.00 0 44659 15 0 0
sp_get_clo_for_invoice 4969 4969 0 0.00 0 23625 329 0 0
sp_kill_cost_storno 15199 15199 0 0.00 0 25935 79 0 0
sp_kill_qstorno_ret_qs2qd 1052908 1052908 0 0.00 0 33643 41 0 0
sp_kill_qty_storno 555834 555834 0 0.00 0 83450 90 0 0
sp_lock_selected_doc 20429 20429 0 0.00 0 8641 5 0 0
sp_make_cost_storno 27512 27512 0 0.00 0 25283 65 0 0
sp_multiply_rows_for_pdistr 27512 27512 0 0.00 0 11815 38 0 0
sp_multiply_rows_for_qdistr 19894 19894 0 0.00 0 12981 189 0 0
sp_pay_from_customer 6820 6820 0 0.00 4 25465 143 0 0
sp_pay_to_supplier 5822 5822 0 0.00 5 23041 158 0 0
sp_payment_common 12642 12642 0 0.00 1 9822 27 0 0
sp_qd_handle_on_cancel_clo 1697 1697 0 0.00 0 13611 377 0 0
sp_qd_handle_on_invoice_upd_sts 10262 10262 0 0.00 0 83450 515 0 0
sp_qd_handle_on_reserve_upd_sts 9901 9901 0 0.00 0 22919 75 0 0
sp_reserve_write_off 9901 9901 0 0.00 7 22969 230 0 0
sp_supplier_invoice 5346 5346 0 0.00 6 145965 4380 0 0
sp_supplier_order 5376 5376 0 0.00 5 48279 1597 0 0
srv_aggregate_perf_data 91 91 0 0.00 3051 259871 21421 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 989 133765 44278 0 0
srv_find_qd_qs_mism 1001778 1001778 0 0.00 0 25432 7 0 0
srv_make_invnt_saldo 430 430 0 0.00 63 114968 4454 0 0
srv_make_money_saldo 726 726 0 0.00 1 17043 707 0 0
srv_recalc_idx_stat 5 5 0 0.00 204433 391358 282869 0 0
t$perf-abend:sp_add_invoice_to_stock 169 169 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4972 4972 0 0.00 0 20167 212 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 5776 126 0 0
t$perf-norm:sp_cancel_client_order 1697 1697 0 0.00 0 14136 26 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 7483 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 1535 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 1183 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 2271 22 0 0
t$perf-norm:sp_cancel_supplier_order 363 363 0 0.00 0 45812 2285 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 4083 15 0 0
t$perf-norm:sp_client_order 19894 19894 0 0.00 0 15898 25 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 3847 12 0 0
t$perf-norm:sp_pay_from_customer 6820 6820 0 0.00 0 12808 14 0 0
t$perf-norm:sp_pay_to_supplier 5822 5822 0 0.00 0 20460 20 0 0
t$perf-norm:sp_reserve_write_off 9901 9901 0 0.00 0 14751 17 0 0
t$perf-norm:sp_supplier_invoice 5346 5346 0 0.00 0 8014 28 0 0
t$perf-norm:sp_supplier_order 5376 5376 0 0.00 0 12907 25 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 215 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 74 2 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 1560 14 0 0
t$perf-norm:srv_make_money_saldo 726 726 0 0.00 0 6379 17 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 7 3 0 0
v_add_invoice_to_stock 10276 9938 338 3.28 0 11292 23 0 338
v_all_customers 35554 35554 0 0.00 0 92 1 0 0
v_all_suppliers 29500 29500 0 0.00 0 22 1 0 0
v_all_wares 39788 39788 0 0.00 0 65 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 4088 13 0 0
v_cancel_client_order 3394 3394 0 0.00 0 4635 8 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 1824 7 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 12870 19 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 5010 17 0 0
v_cancel_supplier_invoice 786 760 26 3.30 0 2120 16 0 26
v_cancel_supplier_order 726 726 0 0.00 0 12555 34 0 0
v_cancel_write_off 1880 1880 0 0.00 0 2477 17 0 0
v_max_id_clo_ord 5373 5373 0 0.00 0 1695 2 0 0
v_max_id_clo_res 970 970 0 0.00 0 3301 17 0 0
v_max_id_ord_sup 5344 5344 0 0.00 0 3864 4 0 0
v_max_non_paid_invoice 1789 1789 0 0.00 0 2646 4 0 0
v_max_non_paid_realizn 3818 3818 0 0.00 0 1375 3 0 0
v_min_id_clo_ord 5373 5373 0 0.00 0 4411 9 0 0
v_min_id_clo_res 970 970 0 0.00 0 10902 33 0 0
v_min_id_ord_sup 5344 5344 0 0.00 0 5950 7 0 0
v_min_non_paid_invoice 5822 5822 0 0.00 0 9810 22 0 0
v_min_non_paid_realizn 6820 6820 0 0.00 0 9801 19 0 0
v_our_firm 10238 10238 0 0.00 0 89 1 0 0
v_reserve_write_off 19798 19798 0 0.00 0 12260 11 0 0
x_lock_depdocs_on_canc_invoice 10758 10758 0 0.00 0 18890 137 0 0
x_lock_depdocs_on_canc_sup_ord 363 363 0 0.00 0 7095 402 0 0
x_make_qstorno_1000_1200 5376 5376 0 0.00 2 48270 1497 0 0
x_make_qstorno_1200_2000 5346 5346 0 0.00 3 139958 4211 0 0
x_make_qstorno_2100_3300 447087 447087 0 0.00 0 70984 195 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-12-2021 10:39:10 to 07-12-2021 10:39:14.

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:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:14 23084 8192000000 9728113600 10024853504 806684544 8.964 100 91 182 1 836624784 7949328 21990912
08:09:43 18085 8192000000 9773809840 10083332096 846781984 9.368 100 90 180 0 879717808 11961024 20974800
08:11:07 24800 8192000000 9808226448 10124021760 877304048 9.673 100 89 178 0 916809600 19100208 20405344
08:12:39 13413 8192000000 9867314832 10196754432 915163808 10.048 100 81 160 1 976330640 43632016 17534816
08:13:58 10562 8192000000 9923757056 10265206784 946682016 10.359 100 85 170 1 1034752512 70030544 18039952
08:15:13 8863 8192000000 9948747312 10301755392 973425120 10.620 100 72 143 1 1058363600 70081200 14857280
08:16:23 3385 8192000000 9953787664 10309828608 1007334208 10.950 100 78 156 1 1068297728 44170800 16792720
08:17:29 2686 8192000000 10025296288 10392662016 1040566160 11.270 100 86 172 1 1138482256 79866384 18049712
08:18:34 1692 8192000000 10018136288 10388127744 1056079248 11.419 100 87 174 1 1131942752 58409728 17453776
08:19:39 2663 8192000000 10044008416 10420256768 1068157696 11.534 100 85 169 0 1155319936 69379104 17783136
08:20:45 1544 8192000000 10013549904 10387693568 1074668160 11.597 100 92 183 0 1127547152 33284208 19594784
08:21:48 2309 8192000000 10018801952 10405228544 1082650256 11.673 100 67 134 1 1134181904 37497840 14033808
08:22:52 10108 8192000000 9997084736 10396815360 1082190736 11.668 100 31 62 1 1109154272 20431648 6531888
08:24:03 11032 8192000000 9991580928 10402226176 1092540304 11.767 100 18 36 0 1117437792 20770352 4127136
08:25:20 27437 8192000000 10024192928 10410901504 1100837296 11.846 100 71 142 1 1127451488 11284048 15330144
08:26:48 6706 8192000000 10021034464 10416275456 1099100624 11.829 100 44 88 0 1130404256 21323456 9980176
08:27:56 6532 8192000000 10004644992 10402050048 1098373760 11.822 100 48 98 4 1121759152 12771504 10613888
08:29:03 18238 8192000000 10014907584 10429186048 1096236992 11.802 100 33 66 0 1122719216 19304272 7177952
08:30:26 5424 8192000000 9974451488 10367361024 1030181168 11.170 96 47 94 3 1068186784 27882352 10123264
08:31:32 2947 8192000000 9953299840 10333720576 1035650624 11.223 100 38 76 1 1067321104 23161696 8508784
08:32:36 63376 8192000000 9942638240 10342178816 994696800 10.827 96 23 46 0 1032186944 32853968 4636176
08:34:46 11488 8192000000 9973958944 10367283200 1045225072 11.315 100 38 77 0 1076478832 22516016 8737744
08:36:01 9823 8192000000 9977440320 10374438912 1058913056 11.446 100 36 73 0 1092586448 25167328 8506064
08:37:18 29437 8192000000 9998215936 10384592896 1080367232 11.651 100 45 93 2 1101288912 11857968 9063712
08:38:55 93050 8192000000 10052400320 10452398080 1101025968 11.847 100 39 78 1 1145860384 36815152 8019264
08:41:34 11004 8192000000 10100671456 10498744320 1119800480 12.025 100 58 117 0 1208343072 76300128 12242464
08:42:56 7722 8192000000 10126348560 10529124352 1128506624 12.107 100 53 102 1 1242655936 103679696 10469616
08:44:10 5266 8192000000 10151827568 10556694528 1144380576 12.257 100 55 107 3 1255291568 99438000 11472992
08:45:21 9341 8192000000 10194884784 10600468480 1152045152 12.329 100 69 135 0 1295779040 129785232 13948656
08:46:45 3106 8192000000 10216328800 10623725568 1153891120 12.346 100 76 153 1 1328096272 157233392 16971760
08:47:52 2398 8192000000 10213978176 10625150976 1164087648 12.442 100 59 117 2 1317333296 140915280 12330368
08:49:01 3385 8192000000 10242110928 10653302784 1176086128 12.554 100 60 118 2 1348304896 158998864 13219904
08:50:07 8859 8192000000 10299936480 10712150016 1144469392 12.258 98 75 148 0 1393209168 231969744 16770032
08:51:24 3485 8192000000 10284734512 10707156992 1164423456 12.445 100 70 141 0 1386837072 205719568 16694048
08:52:34 6849 8192000000 10311593056 10735128576 1170735440 12.504 100 54 108 0 1421905952 238370256 12800256
08:53:57 4488 8192000000 10289928720 10703192064 1178757360 12.579 100 71 142 1 1391788656 197357408 15673888
08:55:04 987 8192000000 10322675744 10721611776 1157358576 12.379 99 80 160 0 1413266096 238159232 17748288
08:56:11 1922 8192000000 10329411696 10749878272 1151621984 12.325 100 85 169 0 1425388704 254702432 19064288
08:57:19 3858 8192000000 10345641760 10752229376 1140636688 12.222 99 83 165 1 1452132256 292401168 19094400
08:58:30 1485 8192000000 10387910208 10782470144 1146158880 12.273 99 92 183 1 1474968432 307139008 21670544
08:59:38 10782 8192000000 10385964304 10820276224 1145402032 12.266 100 47 94 0 1501986320 344961424 11622864
09:00:58 2281 8192000000 10362816784 10770395136 1137414784 12.191 98 87 176 0 1451479296 292929280 21135232
09:02:07 6244 8192000000 10404059040 10857709568 1161605520 12.418 100 52 104 2 1513531744 339436752 12489472
09:03:26 11105 8192000000 10428732416 10856742912 1144660224 12.259 99 31 62 0 1514372432 361403904 8308304
09:04:49 13015 8192000000 10466164656 10892054528 1156294480 12.369 100 40 80 0 1569555600 402333280 10927840
09:06:29 13069 8192000000 10469148256 10947178496 1147439840 12.285 100 30 60 2 1597296304 441586128 8270336
09:07:46 11208 8192000000 10371534896 10845958144 1137649152 12.193 99 38 76 0 1467477696 320545088 9283456
09:09:00 8181 8192000000 10221642768 10752614400 1133314896 12.153 99 30 60 1 1338015312 197985088 6715328
09:10:14 39366 8192000000 10106654320 10528768000 1112757504 11.959 98 29 56 1 1174815936 56050704 6007728
09:12:04 16940 8192000000 10107264544 10519621632 1131987168 12.140 99 60 120 1 1181496336 36252080 13257088
09:13:25 95183 8192000000 10178145904 10673238016 1144345184 12.256 100 22 44 1 1269937536 120251904 5340448
09:16:27 44095 8192000000 10102620480 10600525824 1130566368 12.127 99 40 79 0 1228168720 88096000 9506352
09:18:13 31865 8192000000 10089183264 10525130752 1147634368 12.287 100 36 72 0 1189141104 34429216 7077520
09:19:46 29916 8192000000 10122861856 10580774912 1154968832 12.356 100 26 50 2 1232874496 72484496 5421168
09:21:23 24682 8192000000 10121033712 10580832256 1130898832 12.130 98 47 94 0 1210899856 69711920 10289104
09:23:17 64163 8192000000 10117211808 10585481216 1133596528 12.155 98 12 24 1 1206881216 70781632 2503056
09:25:22 72574 8192000000 10125324928 10565234688 1135145648 12.170 99 20 39 2 1219992768 79802656 5044464
09:28:15 30778 8192000000 10079734848 10557546496 1148118192 12.292 100 15 30 0 1194034576 43128368 2788016
09:30:30 66057 8192000000 10096079264 10566299648 1140002208 12.216 99 24 48 1 1206610672 61057056 5551408
09:33:16 77150 8192000000 10085500080 10592804864 1127882752 12.101 98 16 32 1 1186558608 55148112 3527744
09:36:08 123414 8192000000 10074710880 10567184384 1086090336 11.705 94 20 39 1 1172667216 82108400 4468480
09:39:12 57800 8192000000 10071466144 10531561472 1112631456 11.957 96 15 29 0 1158776960 43407056 2738448
09:41:16 57614 8192000000 10078156992 10532872192 1079469792 11.642 94 24 48 0 1126106704 42104256 4532656
09:43:17 70346 8192000000 10096481584 10555977728 1087241520 11.716 96 27 54 0 1154817744 62034896 5541328
09:45:28 26207 8192000000 10110516976 10535227392 1096731040 11.807 96 41 82 0 1146933968 41863824 8339104
09:46:55 133765 8192000000 10076796608 10552856576 1084802832 11.693 97 21 42 2 1168770672 79491232 4476608
09:50:11 66659 8192000000 10058751872 10546921472 1095916512 11.799 99 28 56 1 1163927712 62110880 5900320
09:52:18 87133 8192000000 10097622384 10529107968 1093368384 11.775 98 15 30 1 1184108624 87463312 3276928
09:54:45 76123 8192000000 10080789392 10520805376 1072240048 11.573 96 23 46 0 1127431408 50407328 4784032
09:57:02 122116 8192000000 10116007456 10576257024 1084920032 11.694 97 19 38 2 1191309568 102054752 4334784
10:00:06 99246 8192000000 10038843648 10467708928 1077306560 11.622 96 18 36 0 1116876000 35919392 3650048
10:02:46 21028 8192000000 10119105520 10562134016 1129087648 12.113 100 47 94 1 1202353392 64065328 9200416
10:04:17 45060 8192000000 10090959728 10516082688 1118488464 12.013 99 27 54 0 1167486000 43333120 5664416
10:06:02 102024 8192000000 10132461312 10562359296 1085818144 11.703 96 21 40 1 1123296656 33373248 4105264
10:08:45 131033 8192000000 10151162864 10602229760 1122725408 12.053 99 28 56 0 1237861056 109287408 5848240
10:11:58 50624 8192000000 10062104592 10538708992 1127126400 12.094 100 10 19 1 1174555952 45424992 2004560
10:13:49 65903 8192000000 10074177744 10541617152 1135583312 12.174 100 7 14 0 1188529376 51829232 1116832
10:16:08 80301 8192000000 10086351392 10554138624 1145894192 12.271 100 15 30 1 1194206208 45528704 2783312
10:18:29 33936 8192000000 10067728448 10516938752 1135380016 12.172 99 29 58 0 1167858512 26424864 6053632
10:20:08 22078 8192000000 10093832304 10552438784 1151754560 12.326 100 30 59 0 1207358528 49796560 5807408
10:21:32 74115 8192000000 10141100608 10609664000 1131609280 12.137 98 32 62 0 1235354048 97072720 6672048
10:23:56 99600 8192000000 10086331680 10586714112 1108624832 11.919 97 9 18 0 1155768640 45450832 1692976
10:26:42 35675 8192000000 10068787456 10496462848 1097458400 11.814 96 25 50 0 1138185744 35802368 4924976
10:28:47 118449 8192000000 10069512848 10504990720 1102409808 11.860 98 25 50 0 1167980560 60240848 5329904
10:31:49 49211 8192000000 10073939120 10528595968 1109800784 11.931 98 26 51 0 1169193216 53337136 6055296
10:34:09 52149 8192000000 10066137696 10506661888 1105991552 11.894 98 18 35 1 1148313280 38182672 4139056
10:36:04 66863 8192000000 10067812640 10554499072 1107914912 11.913 99 15 29 1 1173923456 62886496 3122048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-12-2021 10:39:16 to 07-12-2021 10:39:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 169 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-12-2021 10:39:31 to 07-12-2021 10:39:33.

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          226504
MON$OLDEST_ACTIVE               228062
MON$OLDEST_SNAPSHOT             228062
MON$NEXT_TRANSACTION            228062
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                       2061728
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2676 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2676 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2676 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2676 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Tue Dec  7 10:37:17 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Dec  7 10:37:20 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Tue Dec  7 10:37:24 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Tue Dec  7 10:37:37 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Tue Dec  7 10:37:37 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

07.12.21 10:39:34. 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-12-07 10:39:37 No severe PSQL-related problems occured
07.12.21 10:39:38. End of report.