Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2020 16:28
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 17358988 kB
MemAvailable 31634960 kB
Buffers 0 kB
Cached 14352380 kB
SwapCached 5420 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 3.0.7
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2020-10-14 16:26:59
DTS_END                         2020-10-14 16:26:59.9250
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(56:17)
IP                              ::1/46070
TRN_ID                          249889
ATT_ID                          722
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: 9819.82 19314 1178378
customer order: creation 182.51 332 21901
customer order: refuse 15.18 278 1822
order to supplier: creation 47.81 1152 5737
order to supplier: removal 3.67 68282 440
invoice (draft): creation 49.47 2750 5936
invoice (draft): removal 55.63 514 6675
invoice accept: apply 43.79 14644 5255
invoice accept: cancel 50.88 592 6106
customer reserve: creation 4075.48 144 489058
customer reserve: removal 4975.24 40 597029
realization accept: apply 88.55 165 10626
realization accept: cancel 93.45 81 11214
payment from customer: creation 63.32 116 7598
payment from customer: removal 6.19 55 743
payment to supplier: creation 51.90 139 6228
payment to supplier: removal 3.86 58 463
service: total inventory turnovers 5.19 1016 623
service: total monetary turnovers 6.69 47 803
service: aggregate perf. data 0.98 17171 117
service: refresh index statistics 0.03 278696 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2020 16:28:44 to 14-10-2020 16:28:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3211
TEST_TIME 2 3493
TEST_TIME 3 3937
TEST_TIME 4 4528
TEST_TIME 5 4989
TEST_TIME 6 5297
TEST_TIME 7 5346
TEST_TIME 8 5842
TEST_TIME 9 6101
TEST_TIME 10 6926
TEST_TIME 11 7200
TEST_TIME 12 7581
TEST_TIME 13 7946
TEST_TIME 14 8551
TEST_TIME 15 9051
TEST_TIME 16 9221
TEST_TIME 17 9761
TEST_TIME 18 10316
TEST_TIME 19 10817
TEST_TIME 20 11244
TEST_TIME 21 11371
TEST_TIME 22 11129
TEST_TIME 23 11669
TEST_TIME 24 11590
TEST_TIME 25 11461
TEST_TIME 26 11211
TEST_TIME 27 11008
TEST_TIME 28 10812
TEST_TIME 29 10881
TEST_TIME 30 10837
TEST_TIME 31 10698
TEST_TIME 32 10913
TEST_TIME 33 10932
TEST_TIME 34 10972
TEST_TIME 35 10862
TEST_TIME 36 11013
TEST_TIME 37 11015
TEST_TIME 38 11129
TEST_TIME 39 11061
TEST_TIME 40 11163
TEST_TIME 41 10916
TEST_TIME 42 10756
TEST_TIME 43 10633
TEST_TIME 44 10514
TEST_TIME 45 10595
TEST_TIME 46 10483
TEST_TIME 47 10608
TEST_TIME 48 10549
TEST_TIME 49 10478
TEST_TIME 50 10386
TEST_TIME 51 10289
TEST_TIME 52 10129
TEST_TIME 53 10258
TEST_TIME 54 10157
TEST_TIME 55 10047
TEST_TIME 56 10030
TEST_TIME 57 10013
TEST_TIME 58 9952
TEST_TIME 59 10093
TEST_TIME 60 10062
TEST_TIME 61 10171
TEST_TIME 62 10158
TEST_TIME 63 10222
TEST_TIME 64 10381
TEST_TIME 65 10312
TEST_TIME 66 10306
TEST_TIME 67 10297
TEST_TIME 68 10360
TEST_TIME 69 10373
TEST_TIME 70 10361
TEST_TIME 71 10376
TEST_TIME 72 10287
TEST_TIME 73 10201
TEST_TIME 74 10178
TEST_TIME 75 10104
TEST_TIME 76 10029
TEST_TIME 77 9974
TEST_TIME 78 9950
TEST_TIME 79 9984
TEST_TIME 80 9965
TEST_TIME 81 9919
TEST_TIME 82 9933
TEST_TIME 83 9922
TEST_TIME 84 9995
TEST_TIME 85 10013
TEST_TIME 86 10001
TEST_TIME 87 10015
TEST_TIME 88 10014
TEST_TIME 89 10082
TEST_TIME 90 10115
TEST_TIME 91 10132
TEST_TIME 92 10167
TEST_TIME 93 10117
TEST_TIME 94 10030
TEST_TIME 95 9977
TEST_TIME 96 10017
TEST_TIME 97 9970
TEST_TIME 98 9968
TEST_TIME 99 9935
TEST_TIME 100 9886
TEST_TIME 101 9961
TEST_TIME 102 9992
TEST_TIME 103 9971
TEST_TIME 104 9956
TEST_TIME 105 10013
TEST_TIME 106 9998
TEST_TIME 107 9996
TEST_TIME 108 10056
TEST_TIME 109 10033
TEST_TIME 110 10063
TEST_TIME 111 10047
TEST_TIME 112 9991
TEST_TIME 113 9963
TEST_TIME 114 9919
TEST_TIME 115 9942
TEST_TIME 116 9897
TEST_TIME 117 9872
TEST_TIME 118 9872
TEST_TIME 119 9841
TEST_TIME 120 9824

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-10-2020 16:28:47 to 14-10-2020 16:28:51.

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 1631272 1631272 0 0.00 0 18214 5 0 0
doc_list_biud 1631268 1631268 0 0.00 0 24357 19 0 0
sp_add_invoice_to_stock 5350 5255 95 1.77 28 178640 14644 0 95
sp_cancel_adding_invoice 6106 6106 0 0.00 5 59982 593 0 0
sp_cancel_client_order 1822 1822 0 0.00 4 15426 279 0 0
sp_cancel_customer_reserve 597029 597029 0 0.00 1 14846 40 0 0
sp_cancel_pay_from_customer 743 743 0 0.00 3 12639 56 0 0
sp_cancel_pay_to_supplier 463 463 0 0.00 3 4238 58 0 0
sp_cancel_supplier_invoice 6679 6675 4 0.05 2 18041 514 0 4
sp_cancel_supplier_order 440 440 0 0.00 8 813288 68283 0 0
sp_cancel_write_off 11214 11214 0 0.00 1 22335 82 0 0
sp_client_order 21901 21901 0 0.00 3 18196 332 0 0
sp_customer_reserve 489058 489058 0 0.00 0 77039 145 0 0
sp_fill_shopping_cart 33568 33568 0 0.00 1 8726 34 0 0
sp_fill_shopping_cart_clo_res 489050 489050 0 0.00 0 14155 11 0 0
sp_get_clo_for_invoice 5246 5246 0 0.00 0 20120 272 0 0
sp_kill_cost_storno 18526 18526 0 0.00 0 14987 39 0 0
sp_kill_qstorno_ret_qs2qd 1212387 1212387 0 0.00 0 22322 21 0 0
sp_kill_qty_storno 639158 639158 0 0.00 0 24355 47 0 0
sp_lock_selected_doc 21988 21988 0 0.00 0 7736 5 0 0
sp_make_cost_storno 29698 29698 0 0.00 0 17316 53 0 0
sp_multiply_rows_for_pdistr 29696 29696 0 0.00 0 7280 27 0 0
sp_multiply_rows_for_qdistr 21901 21901 0 0.00 0 12532 153 0 0
sp_pay_from_customer 7598 7598 0 0.00 4 18394 117 0 0
sp_pay_to_supplier 6228 6228 0 0.00 4 17325 139 0 0
sp_payment_common 13824 13824 0 0.00 1 18359 32 0 0
sp_qd_handle_on_cancel_clo 1822 1822 0 0.00 0 15418 239 0 0
sp_qd_handle_on_invoice_upd_sts 11352 11352 0 0.00 0 24352 249 0 0
sp_qd_handle_on_reserve_upd_sts 10626 10626 0 0.00 0 11642 53 0 0
sp_reserve_write_off 10626 10626 0 0.00 6 14449 165 0 0
sp_supplier_invoice 5936 5936 0 0.00 8 80536 2750 0 0
sp_supplier_order 5737 5737 0 0.00 5 26600 1153 0 0
srv_aggregate_perf_data 117 117 0 0.00 2823 228465 17171 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2269 151431 50080 0 0
srv_find_qd_qs_mism 1127702 1127702 0 0.00 0 18209 4 0 0
srv_make_invnt_saldo 623 623 0 0.00 36 104355 1016 0 0
srv_make_money_saldo 803 803 0 0.00 1 13147 48 0 0
srv_recalc_idx_stat 4 4 0 0.00 126955 486377 278697 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5256 5256 0 0.00 0 10355 123 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 0 9381 98 0 0
t$perf-norm:sp_cancel_client_order 1822 1822 0 0.00 0 804 6 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 2776 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 743 743 0 0.00 0 614 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 463 463 0 0.00 0 879 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 2435 18 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 23534 1303 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 1032 5 0 0
t$perf-norm:sp_client_order 21901 21901 0 0.00 0 16638 15 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 680 4 0 0
t$perf-norm:sp_pay_from_customer 7598 7598 0 0.00 0 8583 14 0 0
t$perf-norm:sp_pay_to_supplier 6229 6229 0 0.00 0 9503 12 0 0
t$perf-norm:sp_reserve_write_off 10626 10626 0 0.00 0 9520 11 0 0
t$perf-norm:sp_supplier_invoice 5936 5936 0 0.00 0 15907 22 0 0
t$perf-norm:sp_supplier_order 5737 5737 0 0.00 0 3103 11 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 392 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 6389 105 0 0
t$perf-norm:srv_make_invnt_saldo 623 623 0 0.00 0 797 12 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 1235 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 108 29 0 0
v_add_invoice_to_stock 10678 10488 190 1.77 0 13495 21 0 190
v_all_customers 40230 40230 0 0.00 0 21 0 0 0
v_all_suppliers 32454 32454 0 0.00 0 14 1 0 0
v_all_wares 43802 43802 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 2287 10 0 0
v_cancel_client_order 3644 3644 0 0.00 0 10338 12 0 0
v_cancel_customer_prepayment 1486 1486 0 0.00 0 1019 4 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 11077 11 0 0
v_cancel_payment_to_supplier 926 926 0 0.00 0 1239 5 0 0
v_cancel_supplier_invoice 820 812 8 0.97 0 855 17 0 8
v_cancel_supplier_order 880 880 0 0.00 0 5080 11 0 0
v_cancel_write_off 2092 2092 0 0.00 0 6844 17 0 0
v_max_id_clo_ord 5735 5735 0 0.00 0 308 1 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 4517 24 0 0
v_max_id_ord_sup 5932 5932 0 0.00 0 4779 3 0 0
v_max_non_paid_invoice 1666 1666 0 0.00 0 656 3 0 0
v_max_non_paid_realizn 3973 3973 0 0.00 0 2036 4 0 0
v_min_id_clo_ord 5735 5735 0 0.00 0 2320 6 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 820 3 0 0
v_min_id_ord_sup 5932 5932 0 0.00 0 6704 6 0 0
v_min_non_paid_invoice 6226 6226 0 0.00 0 6705 20 0 0
v_min_non_paid_realizn 7598 7598 0 0.00 0 6765 19 0 0
v_our_firm 10822 10822 0 0.00 0 13 1 0 0
v_reserve_write_off 21252 21252 0 0.00 0 14226 8 0 0
x_lock_depdocs_on_canc_invoice 12375 12375 0 0.00 0 18863 92 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 5190 255 0 0
x_make_qstorno_1000_1200 5737 5737 0 0.00 1 26574 1087 0 0
x_make_qstorno_1200_2000 5936 5936 0 0.00 4 75192 2632 0 0
x_make_qstorno_2100_3300 489058 489058 0 0.00 0 77037 132 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2020 16:28:52 to 14-10-2020 16:28:56.

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
13:57:03 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
13:58:04 34289 8192000000 10388552448 10711957504 1428322400 14.846 100 85 170 0 1461485680 5801728 27361552
13:59:42 29882 8192000000 10457526848 10792517632 1503645200 15.508 100 90 180 0 1541002864 8854560 28503104
14:01:18 16492 8192000000 10491568224 10837508096 1547459536 15.888 100 80 160 1 1582555488 10538656 24557296
14:02:38 9107 8192000000 10589684064 10950987776 1647399696 16.742 100 82 164 2 1683710304 12161104 24149504
14:03:49 3892 8192000000 10647555120 11023106048 1711398384 17.280 100 79 159 3 1748675360 14007232 23269744
14:04:58 4444 8192000000 10712095376 11091271680 1773948880 17.800 100 86 172 0 1811246048 13756992 23540176
14:06:04 1954 8192000000 10772743888 11156201472 1830543104 18.264 100 90 179 1 1869674928 13290032 25841792
14:07:07 1635 8192000000 10823641872 11211091968 1873665920 18.614 100 96 193 1 1914857376 14165424 27026032
14:08:10 8505 8192000000 10821414336 11216150528 1883017632 18.689 100 81 163 4 1915278000 8091024 24169344
14:09:19 4931 8192000000 10806821264 11227369472 1885784144 18.712 100 42 83 1 1905908416 8581664 11542608
14:10:25 9414 8192000000 10797244768 11229499392 1891674352 18.759 100 11 22 1 1900112832 5211088 3227392
14:11:35 3745 8192000000 10810797184 11244326912 1900794592 18.833 100 20 40 1 1913234448 6303360 6136496
14:12:40 47845 8192000000 10844689936 11261435904 1910109440 18.908 100 57 115 2 1937015776 9069296 17837040
14:14:28 6199 8192000000 10823410528 11256565760 1908176160 18.892 100 24 48 1 1920640176 5207584 7256432
14:15:36 54232 8192000000 10820377920 11245780992 1849336944 18.417 97 37 71 1 1863234800 4569472 9328384
14:17:36 4057 8192000000 10799829184 11230597120 1886891520 18.721 100 30 59 3 1901136640 5438208 8806912
14:18:40 7585 8192000000 10705277360 11128864768 1763888208 17.717 97 32 62 5 1780381120 7181728 9311184
14:19:51 35593 8192000000 10711189824 11133960192 1739815424 17.517 96 31 61 3 1753541808 4775872 8950512
14:21:47 5802 8192000000 10721003072 11139530752 1797312976 17.992 99 30 59 2 1812718720 7212992 8192752
14:22:53 9179 8192000000 10765823504 11188236288 1848005360 18.406 100 23 46 1 1860055440 4861408 7188672
14:24:04 12578 8192000000 10791389696 11215618048 1869794656 18.583 100 15 30 1 1879525552 5162768 4568128
14:25:17 58450 8192000000 10789298736 11226284032 1881548672 18.678 100 21 42 0 1893046896 5633696 5864528
14:27:20 24975 8192000000 10829394288 11250118656 1905016112 18.867 100 39 73 2 1921092656 5495552 10580992
14:28:54 33796 8192000000 10874643312 11304357888 1927228512 19.045 99 37 74 0 1948342224 10943904 10169808
14:30:34 20412 8192000000 10893447168 11325972480 1954870992 19.265 100 50 98 0 1988292112 19015472 14405648
14:31:57 2267 8192000000 10906168848 11336429568 1963785872 19.336 100 53 105 0 1997722032 19203840 14732320
14:33:01 6644 8192000000 10917943696 11346620416 1969569344 19.382 100 49 98 1 2005411952 22145408 13697200
14:34:16 7901 8192000000 10950361632 11383746560 1989004288 19.536 100 41 83 2 2035001328 33767280 12229760
14:35:27 3105 8192000000 10965356048 11384033280 1985047424 19.505 99 71 141 0 2040056416 33874752 21134240
14:36:33 4266 8192000000 10958930208 11380834304 1946691280 19.200 97 71 142 1 2006100432 37996496 21412656
14:37:45 6020 8192000000 10950180272 11368681472 1961766320 19.320 99 73 145 1 2020724208 36757408 22200480
14:38:54 7121 8192000000 10942870480 11384926208 1970823232 19.392 99 52 104 0 2028237616 40245936 17168448
14:40:06 14172 8192000000 10908912112 11352154112 1945072464 19.187 100 39 78 2 2001803104 44117920 12612720
14:41:23 13238 8192000000 10895998400 11328577536 1894881760 18.785 97 30 60 1 1940445808 35602704 9961344
14:42:41 6264 8192000000 10867130768 11291226112 1880630368 18.670 98 38 77 4 1930028256 36733792 12664096
14:43:52 19161 8192000000 10851941168 11289763840 1900171168 18.828 100 30 58 3 1947587296 37594144 9821984
14:45:24 21642 8192000000 10841357344 11279384576 1873778976 18.615 99 23 46 1 1910558112 29497888 7281248
14:46:46 24712 8192000000 10842512704 11298234368 1876456480 18.636 98 19 38 2 1914616176 32386864 5772832
14:49:14 36752 8192000000 10835835536 11290628096 1889364672 18.741 99 21 42 0 1926398304 30065200 6968432
14:51:15 121477 8192000000 10857874304 11320094720 1903123344 18.851 99 26 46 2 1917564560 8664336 5776880
14:54:28 29636 8192000000 10870564512 11322105856 1939858368 19.146 100 41 81 3 1962907216 11968816 11080032
14:56:01 97676 8192000000 10894174400 11355639808 1946653344 19.200 100 7 14 0 1962920176 14590992 1675840
14:58:40 151431 8192000000 10903343216 11368247296 1944646096 19.184 98 13 25 1 1960393616 12325904 3421616
15:02:12 117428 8192000000 10933158416 11414880256 1987406624 19.523 100 4 8 0 2007858800 18869824 1582352
15:05:10 38834 8192000000 10919190080 11382898688 1976061920 19.434 99 46 92 1 2002325712 12411952 13851840
15:07:25 96476 8192000000 10941783344 11395760128 1975862688 19.432 99 27 53 2 1991930560 7862448 8205424
15:10:05 37965 8192000000 10955414928 11413962752 2009137488 19.695 100 56 110 5 2040174032 15240480 15796064
15:11:44 22868 8192000000 10959375168 11415109632 2013279008 19.727 100 42 83 1 2038225344 12659888 12286448
15:13:10 57844 8192000000 10951111888 11431247872 2011567200 19.714 100 11 22 0 2036108992 21424784 3117008
15:15:34 58370 8192000000 10887190912 11351449600 1882566784 18.686 94 9 18 0 1893678128 8368256 2743088
15:17:35 35866 8192000000 10868626720 11345285120 1909771840 18.905 97 11 21 1 1920266688 6964192 3530656
15:19:14 59258 8192000000 10870163008 11361386496 1947194160 19.204 99 17 33 1 1960810560 8818080 4798320
15:21:20 80358 8192000000 10845903712 11320582144 1875559952 18.629 97 17 34 0 1886812576 6612432 4640192
15:23:42 42570 8192000000 10881695840 11351670784 1915372240 18.950 98 20 40 1 1932048928 11199104 5477584
15:25:27 40435 8192000000 10871572224 11347472384 1898858928 18.817 97 21 42 1 1915219488 10643088 5717472
15:27:12 39263 8192000000 10841547456 11311820800 1856224208 18.473 96 12 25 0 1872003296 12452688 3326400
15:28:52 70292 8192000000 10869776192 11329544192 1894768848 18.784 98 23 46 0 1911769680 10747840 6252992
15:31:03 31602 8192000000 10845387600 11293827072 1857828128 18.486 97 39 79 0 1877131184 8888352 10414704
15:32:35 58049 8192000000 10842961376 11302989824 1848721888 18.412 96 23 46 0 1868966432 13208128 7036416
15:34:33 51532 8192000000 10845734752 11294502912 1843119040 18.366 96 18 36 1 1862488368 14312400 5056928
15:36:26 67829 8192000000 10864639360 11340562432 1896466080 18.798 98 27 54 1 1923576176 18623200 8486896
15:39:03 50810 8192000000 10865862480 11322515456 1902222144 18.844 98 25 50 0 1917109696 7210240 7677312
15:41:00 40562 8192000000 10861588896 11342028800 1948026048 19.211 100 7 14 1 1956458096 6381968 2050080
15:43:26 73795 8192000000 10900413184 11378847744 1975002624 19.425 100 19 37 1 1993717488 12647184 6067680
15:46:44 50213 8192000000 10897072544 11369652224 1955280832 19.269 99 22 44 1 1973743424 11998160 6464432
15:48:41 60106 8192000000 10913155040 11378081792 1941276368 19.157 98 18 35 1 1962330592 15868528 5185696
15:50:44 59866 8192000000 10898014016 11388870656 1944808032 19.185 99 4 8 0 1961410992 15417920 1185040
15:53:08 58649 8192000000 10907749760 11367526400 1939979312 19.147 98 23 46 0 1959582336 12815712 6787312
15:55:10 67830 8192000000 10927867696 11392434176 1957609968 19.287 99 25 50 0 1986138192 21248000 7280224
15:57:18 112101 8192000000 10923699072 11407007744 1929751056 19.065 97 7 14 0 1951572608 20183456 1638096
16:00:13 110828 8192000000 10916951584 11401048064 1979714880 19.462 99 20 40 0 2000107824 14753200 5639744
16:03:20 67054 8192000000 10913822448 11393155072 1970225648 19.387 99 12 24 1 1989871680 16019744 3626288
16:05:27 58587 8192000000 10928281824 11378528256 1930146288 19.068 97 22 44 2 1952561040 16069440 6345312
16:07:26 67988 8192000000 10886999168 11357007872 1874647824 18.622 96 12 24 1 1900582880 22421504 3513552
16:09:34 24750 8192000000 10912896672 11375706112 1971905792 19.401 100 31 62 2 1991583376 10696672 8980912
16:11:04 54565 8192000000 10879521296 11348725760 1908606096 18.895 97 26 52 2 1933172480 16716064 7850320
16:13:02 21977 8192000000 10893873984 11336286208 1880893152 18.672 95 45 90 0 1904764352 11075472 12795728
16:14:29 128427 8192000000 10890508064 11363213312 1836706112 18.314 94 6 12 0 1851699616 13289888 1703616
16:17:38 86006 8192000000 10849479808 11309379584 1841838656 18.356 95 27 54 2 1856764272 7571168 7354448
16:20:04 44551 8192000000 10851672544 11309776896 1902028288 18.843 99 34 68 2 1922264144 10841472 9394384
16:21:59 52649 8192000000 10846876608 11330666496 1910347872 18.909 99 9 17 2 1923215824 10694896 2173056
16:23:53 54650 8192000000 10847568784 11330535424 1880735568 18.671 98 11 22 0 1901619504 17809760 3074176
16:25:58 49359 8192000000 10847206976 11318636544 1905067456 18.867 99 17 35 0 1922999536 11945328 5986752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 14-10-2020 16:28:58 to 14-10-2020 16:29:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 95 v_add_invoice_to_stock 335544517
user_exc 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-10-2020 16:29:13 to 14-10-2020 16:29:15.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          249888
MON$OLDEST_ACTIVE               252120
MON$OLDEST_SNAPSHOT             252120
MON$NEXT_TRANSACTION            252120
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-03 16:41:55.3470
MON$PAGES                       2052384
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33374 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33374 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:134: 
:135: mscs-test-161.budzdorov.ru    Wed Oct 14 16:27:16 2020
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:137: 
--- end of diff output ---

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

14.10.20 16:29:16. There are 3 logs in /var/tmp/logs-oltp30 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
2020-10-14 16:29:18 No severe PSQL-related problems occured
14.10.20 16:29:19. End of report.