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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 17049444 kB
MemAvailable 31575844 kB
Buffers 0 kB
Cached 14579456 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.0
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-03-07 10:26:57
DTS_END                         2021-03-07 10:26:57.0260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44038
TRN_ID                          220483
ATT_ID                          665
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: 8599.73 19900 1031968
customer order: creation 158.81 444 19057
customer order: refuse 13.99 388 1679
order to supplier: creation 41.34 1367 4961
order to supplier: removal 3.08 150746 369
invoice (draft): creation 41.93 3802 5031
invoice (draft): removal 49.18 1202 5901
invoice accept: apply 38.45 19755 4614
invoice accept: cancel 44.35 976 5322
customer reserve: creation 3629.83 187 435580
customer reserve: removal 4307.49 83 516899
realization accept: apply 76.39 206 9167
realization accept: cancel 76.62 131 9194
payment from customer: creation 54.83 120 6579
payment from customer: removal 5.24 93 629
payment to supplier: creation 45.40 126 5448
payment to supplier: removal 3.10 87 372
service: total inventory turnovers 3.61 3653 433
service: total monetary turnovers 5.33 746 640
service: aggregate perf. data 0.73 19753 88
service: refresh index statistics 0.04 194129 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-03-2021 10:28:12 to 07-03-2021 10:28:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 478
TEST_TIME 2 2530
TEST_TIME 3 3758
TEST_TIME 4 4815
TEST_TIME 5 4819
TEST_TIME 6 4930
TEST_TIME 7 4848
TEST_TIME 8 5116
TEST_TIME 9 5351
TEST_TIME 10 5438
TEST_TIME 11 5543
TEST_TIME 12 5474
TEST_TIME 13 5953
TEST_TIME 14 5854
TEST_TIME 15 5907
TEST_TIME 16 6060
TEST_TIME 17 6138
TEST_TIME 18 6222
TEST_TIME 19 6225
TEST_TIME 20 6335
TEST_TIME 21 6531
TEST_TIME 22 6991
TEST_TIME 23 7225
TEST_TIME 24 7423
TEST_TIME 25 7450
TEST_TIME 26 7810
TEST_TIME 27 7695
TEST_TIME 28 8085
TEST_TIME 29 9222
TEST_TIME 30 9990
TEST_TIME 31 10838
TEST_TIME 32 10625
TEST_TIME 33 10399
TEST_TIME 34 10255
TEST_TIME 35 10051
TEST_TIME 36 10068
TEST_TIME 37 9965
TEST_TIME 38 9786
TEST_TIME 39 9672
TEST_TIME 40 9600
TEST_TIME 41 9620
TEST_TIME 42 9481
TEST_TIME 43 9302
TEST_TIME 44 9336
TEST_TIME 45 9423
TEST_TIME 46 9273
TEST_TIME 47 9195
TEST_TIME 48 9124
TEST_TIME 49 9039
TEST_TIME 50 9143
TEST_TIME 51 9042
TEST_TIME 52 8954
TEST_TIME 53 8921
TEST_TIME 54 8841
TEST_TIME 55 8732
TEST_TIME 56 8705
TEST_TIME 57 8673
TEST_TIME 58 8666
TEST_TIME 59 8674
TEST_TIME 60 8703
TEST_TIME 61 8704
TEST_TIME 62 8854
TEST_TIME 63 8869
TEST_TIME 64 8874
TEST_TIME 65 8914
TEST_TIME 66 8907
TEST_TIME 67 9034
TEST_TIME 68 8967
TEST_TIME 69 8953
TEST_TIME 70 8921
TEST_TIME 71 8849
TEST_TIME 72 8793
TEST_TIME 73 8722
TEST_TIME 74 8687
TEST_TIME 75 8685
TEST_TIME 76 8657
TEST_TIME 77 8644
TEST_TIME 78 8647
TEST_TIME 79 8626
TEST_TIME 80 8684
TEST_TIME 81 8706
TEST_TIME 82 8664
TEST_TIME 83 8710
TEST_TIME 84 8771
TEST_TIME 85 8777
TEST_TIME 86 8827
TEST_TIME 87 8843
TEST_TIME 88 8747
TEST_TIME 89 8695
TEST_TIME 90 8666
TEST_TIME 91 8627
TEST_TIME 92 8584
TEST_TIME 93 8592
TEST_TIME 94 8611
TEST_TIME 95 8607
TEST_TIME 96 8590
TEST_TIME 97 8598
TEST_TIME 98 8642
TEST_TIME 99 8711
TEST_TIME 100 8758
TEST_TIME 101 8740
TEST_TIME 102 8735
TEST_TIME 103 8800
TEST_TIME 104 8814
TEST_TIME 105 8782
TEST_TIME 106 8841
TEST_TIME 107 8839
TEST_TIME 108 8769
TEST_TIME 109 8758
TEST_TIME 110 8712
TEST_TIME 111 8681
TEST_TIME 112 8634
TEST_TIME 113 8603
TEST_TIME 114 8573
TEST_TIME 115 8562
TEST_TIME 116 8589
TEST_TIME 117 8581
TEST_TIME 118 8591
TEST_TIME 119 8629
TEST_TIME 120 8602

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-03-2021 10:28:15 to 07-03-2021 10:28:19.

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 1433889 1433889 0 0.00 0 19812 7 0 0
doc_list_biud 1433889 1433889 0 0.00 0 44836 38 0 0
sp_add_invoice_to_stock 4756 4614 142 2.98 21 223436 19755 0 142
sp_cancel_adding_invoice 5322 5322 0 0.00 5 65291 977 0 0
sp_cancel_client_order 1679 1679 0 0.00 5 11131 389 0 0
sp_cancel_customer_reserve 516899 516899 0 0.00 1 36284 84 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 3 14312 93 0 0
sp_cancel_pay_to_supplier 372 372 0 0.00 3 5054 88 0 0
sp_cancel_supplier_invoice 5912 5901 11 0.18 2 27431 1203 0 11
sp_cancel_supplier_order 369 369 0 0.00 6 1520456 150747 0 0
sp_cancel_write_off 9194 9194 0 0.00 1 7783 131 0 0
sp_client_order 19057 19057 0 0.00 4 24175 444 0 0
sp_customer_reserve 435580 435580 0 0.00 0 65561 187 0 0
sp_fill_shopping_cart 29044 29044 0 0.00 1 29074 50 0 0
sp_fill_shopping_cart_clo_res 435578 435578 0 0.00 0 28121 20 0 0
sp_get_clo_for_invoice 4609 4609 0 0.00 0 34958 441 0 0
sp_kill_cost_storno 15517 15517 0 0.00 0 7781 67 0 0
sp_kill_qstorno_ret_qs2qd 1049262 1049262 0 0.00 0 36281 46 0 0
sp_kill_qty_storno 553140 553140 0 0.00 0 44836 98 0 0
sp_lock_selected_doc 19133 19133 0 0.00 0 6817 7 0 0
sp_make_cost_storno 25803 25803 0 0.00 0 19809 59 0 0
sp_multiply_rows_for_pdistr 25803 25803 0 0.00 0 9936 34 0 0
sp_multiply_rows_for_qdistr 19057 19057 0 0.00 0 18874 260 0 0
sp_pay_from_customer 6579 6579 0 0.00 4 19084 121 0 0
sp_pay_to_supplier 5448 5448 0 0.00 5 24065 127 0 0
sp_payment_common 12027 12027 0 0.00 1 10571 22 0 0
sp_qd_handle_on_cancel_clo 1679 1679 0 0.00 0 11124 348 0 0
sp_qd_handle_on_invoice_upd_sts 9931 9931 0 0.00 0 44835 464 0 0
sp_qd_handle_on_reserve_upd_sts 9167 9167 0 0.00 0 13132 57 0 0
sp_reserve_write_off 9167 9167 0 0.00 7 21627 207 0 0
sp_supplier_invoice 5031 5031 0 0.00 6 109766 3803 0 0
sp_supplier_order 4961 4961 0 0.00 6 27665 1368 0 0
srv_aggregate_perf_data 88 88 0 0.00 2935 324143 19753 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1207 148652 43114 0 0
srv_find_qd_qs_mism 987003 987003 0 0.00 0 13455 6 0 0
srv_make_invnt_saldo 433 433 0 0.00 30 122624 3654 0 0
srv_make_money_saldo 640 640 0 0.00 0 16229 746 0 0
srv_recalc_idx_stat 5 5 0 0.00 173851 236690 194129 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4614 4614 0 0.00 0 11596 179 0 0
t$perf-norm:sp_cancel_adding_invoice 350 350 0 0.00 0 3888 97 0 0
t$perf-norm:sp_cancel_client_order 1679 1679 0 0.00 0 939 11 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 786 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 808 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 372 372 0 0.00 0 885 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 8897 34 0 0
t$perf-norm:sp_cancel_supplier_order 369 369 0 0.00 0 19828 1852 0 0
t$perf-norm:sp_cancel_write_off 938 938 0 0.00 0 395 5 0 0
t$perf-norm:sp_client_order 19057 19057 0 0.00 0 4285 17 0 0
t$perf-norm:sp_customer_reserve 909 909 0 0.00 0 6089 10 0 0
t$perf-norm:sp_pay_from_customer 6579 6579 0 0.00 0 4400 12 0 0
t$perf-norm:sp_pay_to_supplier 5448 5448 0 0.00 0 3674 11 0 0
t$perf-norm:sp_reserve_write_off 9167 9167 0 0.00 0 4655 10 0 0
t$perf-norm:sp_supplier_invoice 5031 5031 0 0.00 0 8915 19 0 0
t$perf-norm:sp_supplier_order 4961 4961 0 0.00 0 8878 20 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 1112 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 438 13 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 334 4 0 0
t$perf-norm:srv_make_money_saldo 640 640 0 0.00 0 3432 12 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 7 4 0 0
v_add_invoice_to_stock 9502 9218 284 2.98 0 22404 28 0 284
v_all_customers 34860 34860 0 0.00 0 93 1 0 0
v_all_suppliers 27744 27744 0 0.00 0 16 1 0 0
v_all_wares 38114 38114 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 700 700 0 0.00 0 878 8 0 0
v_cancel_client_order 3358 3358 0 0.00 0 5142 9 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 8086 9 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 14122 17 0 0
v_cancel_payment_to_supplier 744 744 0 0.00 0 789 4 0 0
v_cancel_supplier_invoice 794 772 22 2.77 0 3303 24 0 22
v_cancel_supplier_order 738 738 0 0.00 0 1039 6 0 0
v_cancel_write_off 1876 1876 0 0.00 0 1594 14 0 0
v_max_id_clo_ord 4961 4961 0 0.00 0 1401 2 0 0
v_max_id_clo_res 909 909 0 0.00 0 1803 16 0 0
v_max_id_ord_sup 5026 5026 0 0.00 0 7597 8 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 309 2 0 0
v_max_non_paid_realizn 3456 3456 0 0.00 0 524 3 0 0
v_min_id_clo_ord 4961 4961 0 0.00 0 3052 6 0 0
v_min_id_clo_res 909 909 0 0.00 0 6104 22 0 0
v_min_id_ord_sup 5026 5026 0 0.00 0 7902 12 0 0
v_min_non_paid_invoice 5448 5448 0 0.00 0 6042 13 0 0
v_min_non_paid_realizn 6579 6579 0 0.00 0 5477 16 0 0
v_our_firm 9500 9500 0 0.00 0 23 1 0 0
v_reserve_write_off 18334 18334 0 0.00 0 21538 11 0 0
x_lock_depdocs_on_canc_invoice 10837 10837 0 0.00 0 20893 133 0 0
x_lock_depdocs_on_canc_sup_ord 369 369 0 0.00 0 27095 517 0 0
x_make_qstorno_1000_1200 4961 4961 0 0.00 2 23275 1285 0 0
x_make_qstorno_1200_2000 5031 5031 0 0.00 3 109749 3600 0 0
x_make_qstorno_2100_3300 435579 435579 0 0.00 0 65543 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-03-2021 10:28:20 to 07-03-2021 10:28:24.

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
07:57:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:01 23463 8192000000 9715942624 10007592960 785113920 8.745 100 87 175 0 814256880 7390464 21752496
07:59:31 19081 8192000000 9748599920 10051280896 824369056 9.143 100 92 184 0 858523392 11830704 22323632
08:00:55 18870 8192000000 9798906704 10116935680 869006144 9.590 100 83 165 1 905054000 17457600 18590256
08:02:21 15533 8192000000 9877105984 10210668544 931199040 10.206 100 76 152 0 982256976 35143056 15914880
08:03:39 7332 8192000000 9931924544 10277482496 959990192 10.489 100 80 160 0 1043814208 67111376 16712640
08:04:48 6194 8192000000 9959674784 10311344128 995342320 10.833 100 82 162 1 1066314288 54942448 16029520
08:05:57 2727 8192000000 10003482976 10362335232 1021405184 11.086 100 91 181 1 1118997600 79375760 18216656
08:07:03 1440 8192000000 10021175360 10386694144 1045210512 11.315 100 90 183 1 1137665152 73583840 18870800
08:08:05 8718 8192000000 10021885216 10400477184 1058455696 11.442 100 67 136 2 1135005216 62871504 13678016
08:09:15 10602 8192000000 9978527248 10369445888 1065705072 11.511 100 24 47 1 1106387584 36238288 4444224
08:10:26 31707 8192000000 9999202032 10388324352 1075006816 11.600 100 68 136 1 1103805360 12784080 16014464
08:11:58 7743 8192000000 10005604032 10417725440 1076992512 11.619 100 50 99 2 1133129872 45809536 10327824
08:13:08 7995 8192000000 9993175488 10391052288 1082794256 11.674 100 32 64 2 1107712448 17527360 7390832
08:14:20 11660 8192000000 10000233888 10413088768 1086997984 11.714 100 24 48 2 1110150160 17374320 5777856
08:15:34 26708 8192000000 10031909280 10418876416 1093987312 11.781 100 65 131 1 1128769216 21126736 13655168
08:17:01 4347 8192000000 10008251824 10406227968 1088726912 11.731 99 63 126 2 1118529040 16494512 13307616
08:18:05 9363 8192000000 9976378096 10369306624 1052054128 11.380 97 31 61 1 1074947136 14654912 8238096
08:19:15 13048 8192000000 9956596960 10348519424 1010695392 10.982 96 37 74 0 1044103664 24785776 8622496
08:20:34 42932 8192000000 9914154128 10306015232 952129376 10.412 93 36 71 1 981431440 21447232 7854832
08:22:21 7925 8192000000 9946769696 10336657408 1012772688 11.002 99 48 97 1 1044767008 21259312 10735008
08:23:29 9220 8192000000 9958038336 10349441024 1036569216 11.232 100 37 71 0 1074774096 30112416 8092464
08:24:40 7056 8192000000 9969768720 10370777088 1055240176 11.411 100 31 62 0 1071605584 9371920 6993488
08:25:51 34246 8192000000 9990215424 10399145984 1061624032 11.472 100 31 59 1 1090080688 22108576 6348080
08:27:33 27225 8192000000 9993659248 10389585920 1077520816 11.624 100 35 71 1 1098837264 13875344 7441104
08:29:05 27178 8192000000 10070745040 10469384192 1105382320 11.889 100 52 103 2 1186487040 70097568 11007152
08:30:35 15674 8192000000 10091949168 10495606784 1129211904 12.114 100 41 82 1 1197126736 59069024 8845808
08:31:54 16592 8192000000 10136160160 10551869440 1141055392 12.225 100 39 77 0 1249453648 99828816 8569440
08:33:19 11367 8192000000 10177962144 10586562560 1149288912 12.303 100 58 115 2 1280773344 118670544 12813888
08:34:45 6303 8192000000 10206150944 10619785216 1156476160 12.370 100 55 110 1 1315422704 146272272 12674272
08:35:56 6064 8192000000 10194430864 10613026816 1168087744 12.479 100 50 100 0 1302812832 122704464 12020624
08:37:07 2104 8192000000 10246767872 10666434560 1180946976 12.599 100 71 141 1 1353767248 157112000 15708272
08:38:14 3904 8192000000 10273441344 10689044480 1183079680 12.619 100 65 130 0 1382444976 184228576 15136720
08:39:23 3234 8192000000 10290512848 10717917184 1183167872 12.620 100 70 137 2 1394507776 195491664 15848240
08:40:32 6003 8192000000 10328673760 10737758208 1192784352 12.709 100 84 163 1 1426021328 214998720 18238256
08:41:46 5367 8192000000 10358371888 10773164032 1188700048 12.671 99 82 162 0 1465421776 257848720 18873008
08:43:01 3346 8192000000 10389529504 10798563328 1183631696 12.624 98 79 158 1 1477191808 275612544 17947568
08:44:09 4579 8192000000 10449067312 10861826048 1201711264 12.792 99 71 141 1 1548028560 329730208 16587088
08:45:18 1943 8192000000 10481652592 10895212544 1198072512 12.758 99 84 168 1 1570944880 352830976 20041392
08:46:24 1203 8192000000 10530928256 10946019328 1212031632 12.888 100 83 163 1 1629641120 397965696 19643792
08:47:32 2627 8192000000 10594161552 11013025792 1200921328 12.785 100 79 157 1 1691569456 471506256 19141872
08:48:41 2893 8192000000 10535841408 10960232448 1181131680 12.601 100 83 166 0 1644218688 443207312 19879696
08:49:47 15334 8192000000 10547276400 10971807744 1160989552 12.413 99 43 86 0 1632960672 460635808 11335312
08:51:06 10524 8192000000 10547766720 10976153600 1160002816 12.403 99 44 88 1 1643671360 471842176 11826368
08:52:26 18274 8192000000 10550086800 11004481536 1170316992 12.500 100 43 85 3 1679940432 498075056 11548384
08:53:57 17667 8192000000 10582296880 11097464832 1151258368 12.321 100 39 78 0 1706494064 544098304 11137392
08:55:21 12429 8192000000 10532464320 11039117312 1127244624 12.095 98 33 66 0 1635046368 497965440 9836304
08:56:35 47302 8192000000 10127524048 10642997248 1125056624 12.075 98 19 38 1 1355988224 226402704 4528896
08:58:32 53825 8192000000 10072160064 10504273920 1117349712 12.002 99 45 89 2 1147077152 19288432 10439008
09:00:27 40711 8192000000 10088170496 10543366144 1123336800 12.059 99 20 39 2 1184918752 57777248 3804704
09:02:20 68475 8192000000 10102377584 10603085824 1139048784 12.207 100 11 22 0 1192765920 51232992 2484144
09:04:34 91500 8192000000 10109506592 10545262592 1116656432 11.995 98 15 30 0 1191773328 71924096 3192800
09:07:07 98223 8192000000 10146803840 10604384256 1140943968 12.224 100 29 58 1 1238032288 90850112 6238208
09:10:54 74605 8192000000 10082100144 10588729344 1127527360 12.098 99 14 28 0 1196621792 65979104 3115328
09:13:25 47118 8192000000 10081321936 10529128448 1131244752 12.133 99 24 48 2 1186268560 49763760 5260048
09:15:19 91267 8192000000 10063003008 10571595776 1092247952 11.764 96 11 22 0 1148877024 54087968 2541104
09:17:51 84359 8192000000 10050331584 10519072768 1113844400 11.969 99 12 24 0 1157141936 40586960 2710576
09:20:16 47402 8192000000 10058559424 10516783104 1121437984 12.041 99 47 94 1 1151091520 19898288 9755248
09:22:16 75363 8192000000 10094442288 10572267520 1104857776 11.884 97 14 28 0 1166697712 58489248 3350688
09:24:33 63941 8192000000 10115844320 10566897664 1129534864 12.117 99 14 27 3 1221341472 88506768 3299840
09:26:37 34123 8192000000 10127479472 10578038784 1125129664 12.075 98 14 28 1 1206699440 78793280 2776496
09:28:16 58306 8192000000 10063205216 10526801920 1100988528 11.847 97 15 30 1 1193374416 89361520 3024368
09:30:15 18919 8192000000 10095811280 10544001024 1133221024 12.152 99 51 101 1 1192098384 48677536 10199824
09:31:35 31616 8192000000 10063446368 10498293760 1091892896 11.761 96 28 56 0 1138483552 41705712 4884944
09:33:11 66130 8192000000 10082486496 10537537536 1097130144 11.810 96 15 30 1 1163414256 63175600 3108512
09:35:17 14166 8192000000 10105021424 10547769344 1132383040 12.144 100 55 110 0 1210820544 67604032 10833472
09:36:45 112338 8192000000 10045628656 10519822336 1113711856 11.968 98 16 32 1 1158758416 41887776 3158784
09:39:38 56390 8192000000 10032504192 10485719040 1099325856 11.831 97 15 30 0 1122793248 20203152 3264240
09:41:35 51631 8192000000 10048094672 10524008448 1073649808 11.587 95 9 18 0 1113975984 38385392 1940784
09:43:27 48887 8192000000 10085048016 10531803136 1114009824 11.970 98 27 54 0 1171954176 52056304 5888048
09:45:31 29183 8192000000 10086818992 10526916608 1089210688 11.735 96 25 51 2 1157815840 63120960 5484192
09:47:01 49000 8192000000 10057079456 10511663104 1123163792 12.057 100 30 59 1 1172128512 42363216 6601504
09:48:56 36027 8192000000 10139688064 10577051648 1117421952 12.003 98 41 82 0 1198517376 72576416 8519008
09:50:53 148652 8192000000 10078539472 10582286336 997832144 10.858 88 12 24 0 1064603584 64417600 2353840
09:54:25 109533 8192000000 10058886976 10524225536 1096687584 11.806 98 13 22 1 1148411776 49113472 2610720
09:58:57 78807 8192000000 10051180000 10524385280 1120641376 12.033 99 10 20 0 1196301936 73421744 2238816
10:01:16 52335 8192000000 10106276480 10547871744 1122940256 12.055 98 22 44 1 1194533936 66239072 5354608
10:03:09 58669 8192000000 10149519328 10608963584 1130704496 12.128 99 28 57 1 1235621744 98675664 6241584
10:05:19 78653 8192000000 10082598448 10573103104 1126518016 12.089 99 7 14 0 1179804064 51346208 1939840
10:07:41 35008 8192000000 10096519024 10533240832 1133904624 12.158 99 34 68 0 1182305456 40893888 7506944
10:09:16 89491 8192000000 10093329712 10556616704 1147227952 12.283 100 13 26 1 1191482928 42008624 2246352
10:11:47 35600 8192000000 10155360784 10588672000 1143605520 12.249 99 46 90 1 1233149536 80110208 9433808
10:13:25 121070 8192000000 10081759712 10589274112 1139038560 12.206 99 22 44 0 1203537472 59884224 4614688
10:16:38 71941 8192000000 10055655888 10503331840 1099573984 11.834 96 13 26 0 1134921648 32386528 2961136
10:19:42 45617 8192000000 10068609872 10518986752 1094812512 11.788 96 18 34 1 1136012240 37478272 3721456
10:21:28 27171 8192000000 10115282416 10568212480 1133567568 12.155 100 31 62 0 1207325152 66205712 7551872
10:22:55 54981 8192000000 10111467424 10565693440 1125229920 12.076 99 30 60 0 1192474720 59903136 7341664
10:24:51 51527 8192000000 10125176656 10567237632 1113211424 11.963 98 19 38 2 1191118544 72888880 5018240
10:26:45 30518 8192000000 9144162112 9453719552 274297136 3.239 24 9 18 0 333859472 57636960 1925376

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-03-2021 10:28:26 to 07-03-2021 10:28:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-03-2021 10:28:42 to 07-03-2021 10:28:44.

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          220783
MON$OLDEST_ACTIVE               220784
MON$OLDEST_SNAPSHOT             220784
MON$NEXT_TRANSACTION            220784
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                       2083360
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:156: 
:157: mscs-test-161.budzdorov.ru    Sun Mar  7 10:27:34 2021
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:159: 
--- end of diff output ---

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

07.03.21 10:28:45. 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-03-07 10:28:47 No severe PSQL-related problems occured
07.03.21 10:28:48. End of report.