Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.08.2020 03:53
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 369f65bbd7e5442bbb810a88d29f958d
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-957.5.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2019-11-01 09:39
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
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.03
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 32644004 kB
MemFree 9836256 kB
MemAvailable 31039024 kB
Buffers 0 kB
Cached 21685608 kB
SwapCached 5800 kB
Command: fdisk -l

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=fbc, val=/opt/fb40/bin
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=report_compress_cmd, val=/usr/bin/7za
param=host, val=localhost
param=port, val=3400
param=usr, val=SYSDBA
param=pwd, val=masterkey
param=tmpdir, val=/var/tmp/logs.oltp40
param=remove_isql_logs, val=if_no_severe_errors
param=working_mode, val=small_03
param=actions_todo_before_reconnect, val=300
param=max_cps, val=25
param=sleep_max, val=0
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=no_auto_undo, val=1
param=recalc_idx_min_interval, val=30
param=used_in_replication, val=0
param=separate_workers, val=1
param=update_conflict_percent, val=0
param=unit_selection_method, val=random
param=detailed_info, val=0
param=mon_unit_perf, val=2
param=mon_unit_list, val=//
param=mon_query_interval, val=60
param=halt_test_on_errors, val=/CK/
param=qmism_verify_bitset, val=1
param=warm_time, val=30
param=test_time, val=120
param=test_intervals, val=60
param=create_with_fw, val=sync
param=create_with_sweep, val=0
param=wait_if_not_exists, val=0
param=wait_after_create, val=0
param=init_docs, val=0
param=expected_workers, val=100
param=init_buff, val=10000
param=wait_for_copy, val=1
param=create_with_debug_objects, val=1
param=create_with_split_heavy_tabs, val=1
param=create_with_separate_qdistr_idx, val=0
param=create_with_compound_columns_order, val=most_selective_first
param=replace_instance, val=1
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=make_html, val=1
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=file_name_with_test_params, val=regular
param=file_name_this_host_info, val=no_host_info
param=gather_hardware_info, val=1
param=use_mtee, val=0
param=is_embed, val=0
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED.  
DTS_END                         2020-08-16 03:51:50.9520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/43650
TRN_ID                          209377
ATT_ID                          547
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: 8086.82 29723 970418
customer order: creation 150.29 419 18035
customer order: refuse 12.99 545 1559
order to supplier: creation 40.05 1468 4806
order to supplier: removal 3.02 144046 362
invoice (draft): creation 40.43 4742 4852
invoice (draft): removal 46.80 1042 5616
invoice accept: apply 36.33 22563 4359
invoice accept: cancel 42.16 1072 5059
customer reserve: creation 3332.29 221 399875
customer reserve: removal 4125.86 82 495103
realization accept: apply 73.71 237 8845
realization accept: cancel 72.37 169 8684
payment from customer: creation 50.34 148 6041
payment from customer: removal 5.07 151 608
payment to supplier: creation 42.65 152 5118
payment to supplier: removal 3.16 103 379
service: total inventory turnovers 3.20 5030 384
service: total monetary turnovers 5.28 932 634
service: aggregate perf. data 0.79 16819 95
service: refresh index statistics 0.03 394514 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-08-2020 03:53:35 to 16-08-2020 03:53:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1196
TEST_TIME 2 1870
TEST_TIME 3 1660
TEST_TIME 4 1822
TEST_TIME 5 2094
TEST_TIME 6 2513
TEST_TIME 7 2714
TEST_TIME 8 2876
TEST_TIME 9 3189
TEST_TIME 10 3383
TEST_TIME 11 3556
TEST_TIME 12 3643
TEST_TIME 13 3737
TEST_TIME 14 3796
TEST_TIME 15 3829
TEST_TIME 16 3962
TEST_TIME 17 4432
TEST_TIME 18 4552
TEST_TIME 19 4538
TEST_TIME 20 4823
TEST_TIME 21 4895
TEST_TIME 22 5073
TEST_TIME 23 5066
TEST_TIME 24 5272
TEST_TIME 25 5316
TEST_TIME 26 5714
TEST_TIME 27 5923
TEST_TIME 28 6700
TEST_TIME 29 7320
TEST_TIME 30 7164
TEST_TIME 31 7622
TEST_TIME 32 7990
TEST_TIME 33 8138
TEST_TIME 34 8592
TEST_TIME 35 8742
TEST_TIME 36 8554
TEST_TIME 37 8537
TEST_TIME 38 8636
TEST_TIME 39 8574
TEST_TIME 40 8562
TEST_TIME 41 8499
TEST_TIME 42 8507
TEST_TIME 43 8499
TEST_TIME 44 8495
TEST_TIME 45 8581
TEST_TIME 46 8671
TEST_TIME 47 8514
TEST_TIME 48 8346
TEST_TIME 49 8209
TEST_TIME 50 8156
TEST_TIME 51 8229
TEST_TIME 52 8176
TEST_TIME 53 8128
TEST_TIME 54 8079
TEST_TIME 55 8073
TEST_TIME 56 7979
TEST_TIME 57 8026
TEST_TIME 58 8014
TEST_TIME 59 7982
TEST_TIME 60 8069
TEST_TIME 61 8041
TEST_TIME 62 8108
TEST_TIME 63 8133
TEST_TIME 64 8102
TEST_TIME 65 8225
TEST_TIME 66 8163
TEST_TIME 67 8203
TEST_TIME 68 8190
TEST_TIME 69 8351
TEST_TIME 70 8298
TEST_TIME 71 8264
TEST_TIME 72 8340
TEST_TIME 73 8360
TEST_TIME 74 8248
TEST_TIME 75 8158
TEST_TIME 76 8113
TEST_TIME 77 8026
TEST_TIME 78 7972
TEST_TIME 79 7983
TEST_TIME 80 7977
TEST_TIME 81 8019
TEST_TIME 82 7973
TEST_TIME 83 8037
TEST_TIME 84 8057
TEST_TIME 85 8074
TEST_TIME 86 8126
TEST_TIME 87 8107
TEST_TIME 88 8117
TEST_TIME 89 8170
TEST_TIME 90 8269
TEST_TIME 91 8281
TEST_TIME 92 8201
TEST_TIME 93 8143
TEST_TIME 94 8106
TEST_TIME 95 8091
TEST_TIME 96 8107
TEST_TIME 97 8078
TEST_TIME 98 8105
TEST_TIME 99 8144
TEST_TIME 100 8159
TEST_TIME 101 8162
TEST_TIME 102 8144
TEST_TIME 103 8216
TEST_TIME 104 8231
TEST_TIME 105 8285
TEST_TIME 106 8311
TEST_TIME 107 8314
TEST_TIME 108 8315
TEST_TIME 109 8324
TEST_TIME 110 8260
TEST_TIME 111 8233
TEST_TIME 112 8183
TEST_TIME 113 8134
TEST_TIME 114 8096
TEST_TIME 115 8054
TEST_TIME 116 8056
TEST_TIME 117 8118
TEST_TIME 118 8094
TEST_TIME 119 8099
TEST_TIME 120 8105

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-08-2020 03:53:40 to 16-08-2020 03:53:43.

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 1340591 1340591 0 0.00 0 23909 9 0 0
doc_list_biud 1340590 1340590 0 0.00 0 33303 38 0 0
sp_add_invoice_to_stock 4463 4359 104 2.33 49 216103 22563 0 104
sp_cancel_adding_invoice 5059 5059 0 0.00 4 119607 1073 0 0
sp_cancel_client_order 1559 1559 0 0.00 4 11332 546 0 0
sp_cancel_customer_reserve 495103 495103 0 0.00 0 33309 82 0 0
sp_cancel_pay_from_customer 608 608 0 0.00 3 5848 152 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 4832 104 0 0
sp_cancel_supplier_invoice 5625 5616 9 0.16 1 25622 1043 0 9
sp_cancel_supplier_order 362 362 0 0.00 15 1434166 144046 0 0
sp_cancel_write_off 8684 8684 0 0.00 1 9529 169 0 0
sp_client_order 18035 18035 0 0.00 3 21912 419 0 0
sp_customer_reserve 399875 399875 0 0.00 0 77361 222 0 0
sp_fill_shopping_cart 27674 27674 0 0.00 1 25911 51 0 0
sp_fill_shopping_cart_clo_res 399875 399875 0 0.00 0 27644 18 0 0
sp_get_clo_for_invoice 4359 4359 0 0.00 0 28696 446 0 0
sp_kill_cost_storno 14730 14730 0 0.00 0 9310 105 0 0
sp_kill_qstorno_ret_qs2qd 1004868 1004868 0 0.00 0 33295 44 0 0
sp_kill_qty_storno 529587 529587 0 0.00 0 33302 95 0 0
sp_lock_selected_doc 18246 18246 0 0.00 0 10729 7 0 0
sp_make_cost_storno 24363 24363 0 0.00 0 15096 70 0 0
sp_multiply_rows_for_pdistr 24363 24363 0 0.00 0 11659 45 0 0
sp_multiply_rows_for_qdistr 18035 18035 0 0.00 0 21824 218 0 0
sp_pay_from_customer 6041 6041 0 0.00 4 15919 148 0 0
sp_pay_to_supplier 5118 5118 0 0.00 5 14804 153 0 0
sp_payment_common 11158 11158 0 0.00 1 12120 32 0 0
sp_qd_handle_on_cancel_clo 1559 1559 0 0.00 0 11327 502 0 0
sp_qd_handle_on_invoice_upd_sts 9418 9418 0 0.00 0 27440 466 0 0
sp_qd_handle_on_reserve_upd_sts 8845 8845 0 0.00 0 11102 66 0 0
sp_reserve_write_off 8845 8845 0 0.00 5 18762 237 0 0
sp_supplier_invoice 4852 4852 0 0.00 6 108526 4742 0 0
sp_supplier_order 4806 4806 0 0.00 4 32458 1469 0 0
srv_aggregate_perf_data 95 95 0 0.00 2557 123041 16820 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1331 147533 43192 0 0
srv_find_qd_qs_mism 929023 929023 0 0.00 0 23909 7 0 0
srv_make_invnt_saldo 384 384 0 0.00 28 126989 5030 0 0
srv_make_money_saldo 634 634 0 0.00 1 23359 933 0 0
srv_recalc_idx_stat 4 4 0 0.00 216304 594489 394515 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4359 4359 0 0.00 0 14373 255 0 0
t$perf-norm:sp_cancel_adding_invoice 344 344 0 0.00 0 4294 132 0 0
t$perf-norm:sp_cancel_client_order 1559 1559 0 0.00 0 3145 14 0 0
t$perf-norm:sp_cancel_customer_reserve 572 572 0 0.00 0 1193 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 608 608 0 0.00 0 859 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 127 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 339 339 0 0.00 0 3115 28 0 0
t$perf-norm:sp_cancel_supplier_order 362 362 0 0.00 0 31637 2704 0 0
t$perf-norm:sp_cancel_write_off 880 880 0 0.00 0 1130 4 0 0
t$perf-norm:sp_client_order 18035 18035 0 0.00 0 8334 26 0 0
t$perf-norm:sp_customer_reserve 897 897 0 0.00 0 2843 10 0 0
t$perf-norm:sp_pay_from_customer 6041 6041 0 0.00 0 8992 13 0 0
t$perf-norm:sp_pay_to_supplier 5118 5118 0 0.00 0 4567 12 0 0
t$perf-norm:sp_reserve_write_off 8845 8845 0 0.00 0 4629 13 0 0
t$perf-norm:sp_supplier_invoice 4852 4852 0 0.00 0 8406 26 0 0
t$perf-norm:sp_supplier_order 4806 4806 0 0.00 0 8342 23 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 1175 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 30 1 0 0
t$perf-norm:srv_make_invnt_saldo 384 384 0 0.00 0 5070 30 0 0
t$perf-norm:srv_make_money_saldo 634 634 0 0.00 0 748 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 6 0 0
v_add_invoice_to_stock 8926 8718 208 2.33 0 8941 19 0 208
v_all_customers 32420 32420 0 0.00 0 24 1 0 0
v_all_suppliers 26504 26504 0 0.00 0 24 1 0 0
v_all_wares 36070 36070 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 688 688 0 0.00 0 938 10 0 0
v_cancel_client_order 3118 3118 0 0.00 0 2320 7 0 0
v_cancel_customer_prepayment 1216 1216 0 0.00 0 2321 11 0 0
v_cancel_customer_reserve 1144 1144 0 0.00 0 2834 9 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 1144 6 0 0
v_cancel_supplier_invoice 696 678 18 2.58 0 3081 22 0 18
v_cancel_supplier_order 724 724 0 0.00 0 2242 13 0 0
v_cancel_write_off 1760 1760 0 0.00 0 2953 22 0 0
v_max_id_clo_ord 4805 4805 0 0.00 0 238 1 0 0
v_max_id_clo_res 897 897 0 0.00 0 3622 16 0 0
v_max_id_ord_sup 4834 4834 0 0.00 0 4139 4 0 0
v_max_non_paid_invoice 1505 1505 0 0.00 0 5971 6 0 0
v_max_non_paid_realizn 3262 3262 0 0.00 0 4883 4 0 0
v_min_id_clo_ord 4805 4805 0 0.00 0 3461 7 0 0
v_min_id_clo_res 897 897 0 0.00 0 4063 17 0 0
v_min_id_ord_sup 4834 4834 0 0.00 0 5998 8 0 0
v_min_non_paid_invoice 5118 5118 0 0.00 0 10693 23 0 0
v_min_non_paid_realizn 6040 6040 0 0.00 0 12117 24 0 0
v_our_firm 9206 9206 0 0.00 0 20 1 0 0
v_reserve_write_off 17688 17688 0 0.00 0 3526 8 0 0
x_lock_depdocs_on_canc_invoice 10336 10336 0 0.00 0 20949 159 0 0
x_lock_depdocs_on_canc_sup_ord 362 362 0 0.00 0 6871 484 0 0
x_make_qstorno_1000_1200 4806 4806 0 0.00 1 32449 1369 0 0
x_make_qstorno_1200_2000 4852 4852 0 0.00 3 101988 4543 0 0
x_make_qstorno_2100_3300 399875 399875 0 0.00 0 77337 200 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-08-2020 03:53:44 to 16-08-2020 03:53:48.

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
01:21:54 6 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
01:22:56 20909 8192000000 9711637696 9998671872 781981616 8.713 100 87 174 0 810614256 7155408 21477232
01:24:22 13947 8192000000 9739176480 10040115200 821396144 9.113 100 83 167 0 851673904 10650496 19627264
01:25:43 25896 8192000000 9779154496 10089168896 854143088 9.442 100 86 172 0 889584240 15610432 19830720
01:27:14 19125 8192000000 9840353936 10162061312 893656576 9.835 100 78 156 0 940605088 29338576 17609936
01:28:38 11979 8192000000 9895880128 10232815616 942584512 10.318 100 80 160 2 1004147088 44786912 16775664
01:29:52 9677 8192000000 9918555184 10261561344 970362816 10.590 100 82 164 0 1029922928 42266864 17293248
01:31:05 4885 8192000000 9983010736 10337226752 992752416 10.808 100 79 159 1 1097587312 88771120 16063776
01:32:11 12280 8192000000 9996892656 10352676864 1013754128 11.012 100 84 167 1 1107026544 77243408 16029008
01:33:29 3048 8192000000 10024799920 10387496960 1035781984 11.224 100 90 178 0 1141736480 87543696 18410800
01:34:38 2932 8192000000 10044981744 10410430464 1047657136 11.338 100 90 177 1 1158445328 92793040 17995152
01:35:44 2029 8192000000 10027733680 10395279360 1054504752 11.404 100 86 172 2 1134445712 62425616 17515344
01:36:50 1085 8192000000 10024972896 10399436800 1059923792 11.456 100 77 156 0 1144744192 68520240 16300160
01:37:57 1675 8192000000 10018283200 10390388736 1061668064 11.472 100 90 179 2 1136151072 56044432 18438576
01:39:00 8698 8192000000 9967960864 10366918656 1060183552 11.458 100 21 43 0 1091197280 26729840 4283888
01:40:12 7962 8192000000 9978500064 10378858496 1073335600 11.584 100 33 66 1 1110177936 29961216 6881120
01:41:24 9137 8192000000 9993243120 10395742208 1078945088 11.637 100 26 52 1 1109528912 25180832 5402992
01:42:34 6237 8192000000 10008730384 10404823040 1086673888 11.711 100 34 66 2 1118794976 24151488 7969600
01:43:42 24107 8192000000 10002236464 10407264256 1074880240 11.599 99 25 49 1 1106047040 26389888 4776912
01:45:11 5715 8192000000 9979521968 10362810368 1031657216 11.184 96 39 78 5 1067232240 26896816 8678208
01:46:18 5143 8192000000 9974717040 10354311168 1059927792 11.456 100 43 86 2 1084493312 14526208 10039312
01:47:24 50431 8192000000 9956280336 10325524480 980825536 10.692 93 38 75 2 1002675312 14109840 7739936
01:49:16 5719 8192000000 9942793392 10325594112 990321824 10.785 96 44 87 2 1027650368 27676784 9651760
01:50:23 11326 8192000000 9940710656 10326163456 1021353056 11.085 100 46 91 4 1054213504 22385360 10475088
01:51:35 11979 8192000000 9972324352 10366148608 1047037248 11.332 99 25 50 3 1074944000 22368128 5538624
01:52:54 32781 8192000000 9990754960 10378297344 1075289712 11.603 100 41 82 1 1105913872 21109264 9514896
01:54:30 71315 8192000000 10027739168 10425606144 1081190672 11.659 100 38 74 2 1123944992 34825888 7928432
01:56:52 16630 8192000000 10093161280 10499547136 1100300976 11.840 100 48 95 5 1200069088 90199568 9568544
01:58:13 21092 8192000000 10107052560 10504196096 1110031616 11.933 100 38 76 1 1212079552 93778576 8269360
01:59:44 6268 8192000000 10117937648 10528587776 1119424640 12.022 100 46 92 2 1239928640 110543296 9960704
02:00:55 19859 8192000000 10169094064 10569818112 1126900672 12.092 100 50 98 2 1271374864 133421280 11052912
02:02:21 15984 8192000000 10181314592 10584666112 1137133024 12.189 100 56 112 2 1285057808 135393488 12531296
02:03:59 7355 8192000000 10233747728 10640621568 1152868096 12.336 100 56 112 2 1337301120 172260848 12172176
02:05:11 6385 8192000000 10275778704 10677235712 1163786208 12.439 100 65 129 1 1383508896 205042672 14680016
02:06:25 7606 8192000000 10334270768 10731679744 1165200160 12.452 100 80 155 2 1437533984 253914256 18419568
02:07:40 5244 8192000000 10352370192 10760876032 1179371968 12.584 100 67 134 2 1452997920 257770528 15855424
02:08:53 6800 8192000000 10376161248 10776301568 1179832240 12.589 100 80 158 1 1478743696 281388432 17523024
02:10:03 2386 8192000000 10394491344 10799681536 1182771856 12.616 100 85 168 0 1492469008 290708864 18988288
02:11:16 1330 8192000000 10431158544 10828201984 1174763488 12.541 100 83 164 2 1524723136 331210896 18748752
02:12:24 5770 8192000000 10443007936 10846523392 1170735232 12.504 100 75 148 4 1540864288 352462272 17666784
02:13:37 6853 8192000000 10433934192 10849722368 1161174432 12.414 100 67 131 1 1538935968 362127312 15634224
02:14:50 2928 8192000000 10482844160 10881781760 1163042896 12.432 100 91 180 1 1583155840 399071696 21041248
02:16:01 3512 8192000000 10514289040 10921566208 1151669184 12.325 100 86 174 3 1607043568 434096576 21277808
02:17:11 1974 8192000000 10533953120 10953940992 1151191968 12.321 100 88 172 3 1629075728 457277920 20605840
02:18:22 4789 8192000000 10486367696 10897645568 1158883872 12.393 100 75 150 2 1580759504 404111536 17764096
02:19:33 4290 8192000000 10420574480 10867511296 1142969744 12.243 100 69 138 1 1549785872 389846480 16969648
02:20:50 7465 8192000000 10323964336 10813440000 1143852992 12.252 100 28 56 2 1430024976 278642400 7529584
02:22:05 22249 8192000000 10337513648 10778861568 1135994416 12.178 100 30 60 1 1453313584 309553168 7766000
02:23:33 23691 8192000000 10206228272 10656858112 1122552960 12.051 99 17 34 0 1326009104 199529216 3926928
02:24:59 14762 8192000000 10237156128 10676768768 1143260512 12.246 100 33 66 3 1334054224 183480288 7313424
02:26:43 20635 8192000000 10057541424 10523369472 1105610256 11.891 98 15 30 2 1145529136 36820640 3098240
02:28:05 2986 8192000000 10101203680 10508025856 1103294016 11.869 98 76 152 0 1168608496 49364912 15949568
02:29:18 20634 8192000000 10147377984 10590687232 1109957520 11.932 99 30 60 1 1239343536 122296384 7089632
02:31:29 10423 8192000000 10063714176 10508333056 1120951040 12.036 100 43 78 3 1178550272 49854160 7745072
02:32:42 139787 8192000000 10131467728 10588434432 1114202192 11.972 98 15 30 2 1201985200 84234832 3548176
02:36:26 130169 8192000000 10043283200 10541006848 1089177728 11.735 97 11 22 2 1142082016 50414416 2489872
02:39:38 89809 8192000000 10056600080 10483286016 1106651168 11.901 99 31 61 2 1146018176 33080000 6287008
02:42:11 35895 8192000000 10055122336 10510577664 1119975776 12.027 100 41 81 2 1199166960 70783824 8407360
02:43:56 78057 8192000000 10089764544 10533036032 1109905600 11.932 99 11 22 2 1146880016 34102736 2871680
02:46:17 75832 8192000000 10097106144 10551455744 1098131120 11.820 97 10 20 0 1186456464 85907440 2417904
02:48:38 31466 8192000000 10070814432 10524983296 1126530480 12.089 100 12 23 0 1186900624 58126608 2243536
02:50:12 48785 8192000000 10077278704 10534793216 1108843728 11.921 98 20 40 1 1179832688 66673344 4315616
02:52:03 32831 8192000000 10030088736 10453512192 1075998976 11.609 95 27 54 3 1094561744 12643792 5918976
02:53:37 47852 8192000000 10045705056 10515509248 1118833664 12.016 99 15 30 1 1172998208 50906448 3258096
02:55:35 20199 8192000000 10102690528 10528526336 1123177040 12.057 99 44 88 2 1170334064 38245840 8911184
02:56:57 24250 8192000000 10066536320 10499297280 1132675424 12.147 100 31 62 3 1167346400 27915392 6755584
02:58:25 37284 8192000000 10089125824 10522386432 1099500592 11.833 97 26 52 1 1157180096 52688704 4990800
03:00:06 101642 8192000000 10065976368 10500505600 1075735456 11.607 95 19 38 1 1117054192 37273216 4045520
03:02:50 59386 8192000000 10111757968 10558996480 1110055312 11.933 98 48 97 0 1187136048 67420176 9660560
03:04:52 73556 8192000000 10034884736 10461515776 1111189824 11.944 99 16 32 1 1158832720 44356064 3286832
03:07:08 46398 8192000000 10060899488 10510131200 1125497008 12.079 100 7 14 0 1156046960 28825776 1724176
03:08:56 62302 8192000000 10085084096 10542223360 1106565232 11.900 98 12 24 0 1148932368 39452960 2914176
03:10:59 48713 8192000000 10060942336 10512539648 1086767072 11.712 97 10 18 1 1155708032 67250592 1690368
03:12:55 105768 8192000000 10080160080 10559750144 1094919360 11.789 97 23 46 0 1171202736 71293280 4990096
03:15:42 52658 8192000000 10064626912 10518802432 1090910736 11.751 97 11 22 0 1135891168 42678272 2302160
03:17:35 58623 8192000000 10049151936 10504974336 1087875472 11.722 97 29 58 0 1139256752 45422208 5959072
03:19:38 68107 8192000000 10078218560 10529071104 1112812800 11.959 99 27 54 1 1179003888 60946192 5244896
03:21:50 97220 8192000000 10049459872 10515050496 1093377280 11.775 97 13 26 0 1164633184 68500528 2755376
03:25:18 49530 8192000000 10040056000 10484801536 1095625168 11.796 98 4 8 0 1119016112 23012000 378944
03:27:10 60080 8192000000 10067947984 10560094208 1112129904 11.953 99 9 18 0 1170111632 56120176 1861552
03:29:11 66755 8192000000 10094183104 10527293440 1125242352 12.076 99 21 42 1 1193204224 63438992 4522880
03:31:24 45662 8192000000 10077304736 10568871936 1114397152 11.974 99 17 34 0 1187424944 69341424 3686368
03:33:10 67348 8192000000 10070604016 10548629504 1111693984 11.948 99 17 33 1 1187401904 71853072 3854848
03:35:17 51089 8192000000 10122316656 10584014848 1135700720 12.175 100 38 76 2 1224515008 81422336 7391952
03:37:09 97052 8192000000 10080853408 10514157568 1053700864 11.396 93 5 10 0 1092643792 37847648 1095280
03:40:46 147533 8192000000 10041217488 10487521280 1109376624 11.927 99 20 40 0 1168283296 54619008 4287664
03:44:14 73269 8192000000 10091894272 10534694912 1117376256 12.002 99 13 26 0 1172208192 52052912 2779024
03:46:28 83931 8192000000 10107550416 10604535808 1124552496 12.070 100 11 22 0 1225329328 98416352 2360480
03:48:54 43364 8192000000 10072608944 10505936896 1118631488 12.014 99 17 34 1 1161155728 38762880 3761360
03:50:38 81203 8192000000 9038313168 9318871040 183324096 2.188 16 6 12 1 208706624 24014224 1368304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-08-2020 03:53:50 to 16-08-2020 03:54:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 104 v_add_invoice_to_stock 335544517
user_exc 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-08-2020 03:54:05 to 16-08-2020 03:54:07.

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          207496
MON$OLDEST_ACTIVE               209678
MON$OLDEST_SNAPSHOT             209678
MON$NEXT_TRANSACTION            209678
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                       2057312
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000003c06020000000000
MON$NEXT_ATTACHMENT             661
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2158 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2158 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2158 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.2158 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Sun Aug 16 03:53:04 2020
:34:     INET/inet_error: read errno = 104, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Sun Aug 16 03:53:04 2020
:38:     /opt/fb40/bin/fbguard: /opt/fb40/bin/firebird terminated abnormally (-1)
:39: 
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sun Aug 16 03:53:04 2020
:43:     /opt/fb40/bin/fbguard: guardian starting /opt/fb40/bin/firebird
:44: 
:45: 
:46: 
:47: mscs-test-161.budzdorov.ru    Sun Aug 16 03:53:04 2020
:48:     REMOTE INTERFACE/gds__detach: Unsuccesful detach from database.
:49:     Uncommitted work may have been lost.
:50:     Error reading data from the connection.
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Sun Aug 16 03:53:04 2020
:54:     REMOTE INTERFACE/gds__detach: Unsuccesful detach from database.
:55:     Uncommitted work may have been lost.
:56:     Error reading data from the connection.
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Sun Aug 16 03:53:04 2020
:60:     REMOTE INTERFACE/gds__detach: Unsuccesful detach from database.
:61:     Uncommitted work may have been lost.
:62:     Error writing data to the connection.
:63:     send_packet/send
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Sun Aug 16 03:53:04 2020
:67:     REMOTE INTERFACE/gds__detach: Unsuccesful detach from database.
:68:     Uncommitted work may have been lost.
:69:     Error writing data to the connection.
:70:     send_packet/send
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Sun Aug 16 03:53:04 2020
:74:     REMOTE INTERFACE/gds__detach: Unsuccesful detach from database.
:75:     Uncommitted work may have been lost.
:76:     Error writing data to the connection.
:77:     send_packet/send
:78: 
--- end of diff output ---

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

16.08.20 03:54:09. 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: 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-08-16 03:54:11 NO_SEVERE_ERRORS_FOUND
16.08.20 03:54:13. End of report.