Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.08.2020 06:52
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 16691928 kB
MemAvailable 30941288 kB
Buffers 0 kB
Cached 14950016 kB
SwapCached 6164 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-15 06:51:48.6720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/58946
TRN_ID                          208723
ATT_ID                          507
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: 8252.73 26821 990328
customer order: creation 149.57 458 17948
customer order: refuse 12.43 577 1492
order to supplier: creation 40.48 1508 4858
order to supplier: removal 3.05 166615 366
invoice (draft): creation 40.39 4226 4847
invoice (draft): removal 48.63 1160 5835
invoice accept: apply 35.92 22766 4310
invoice accept: cancel 43.36 1027 5203
customer reserve: creation 3373.98 218 404877
customer reserve: removal 4249.88 94 509986
realization accept: apply 72.43 217 8691
realization accept: cancel 71.24 160 8549
payment from customer: creation 51.78 125 6213
payment from customer: removal 4.78 136 574
payment to supplier: creation 43.36 149 5203
payment to supplier: removal 2.73 146 328
service: total inventory turnovers 2.77 6233 332
service: total monetary turnovers 5.28 977 634
service: aggregate perf. data 0.65 30378 78
service: refresh index statistics 0.03 299250 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-08-2020 06:52:48 to 15-08-2020 06:52:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1368
TEST_TIME 2 1574
TEST_TIME 3 2574
TEST_TIME 4 3963
TEST_TIME 5 4278
TEST_TIME 6 4285
TEST_TIME 7 4343
TEST_TIME 8 4377
TEST_TIME 9 4447
TEST_TIME 10 4303
TEST_TIME 11 4186
TEST_TIME 12 4467
TEST_TIME 13 4512
TEST_TIME 14 4614
TEST_TIME 15 4637
TEST_TIME 16 4893
TEST_TIME 17 4951
TEST_TIME 18 4984
TEST_TIME 19 5296
TEST_TIME 20 5753
TEST_TIME 21 5732
TEST_TIME 22 5821
TEST_TIME 23 5712
TEST_TIME 24 5731
TEST_TIME 25 5731
TEST_TIME 26 5911
TEST_TIME 27 6733
TEST_TIME 28 6653
TEST_TIME 29 7025
TEST_TIME 30 7145
TEST_TIME 31 7545
TEST_TIME 32 8117
TEST_TIME 33 8949
TEST_TIME 34 9009
TEST_TIME 35 9317
TEST_TIME 36 9386
TEST_TIME 37 9168
TEST_TIME 38 9000
TEST_TIME 39 8946
TEST_TIME 40 8830
TEST_TIME 41 8737
TEST_TIME 42 8638
TEST_TIME 43 8693
TEST_TIME 44 8659
TEST_TIME 45 8639
TEST_TIME 46 8599
TEST_TIME 47 8576
TEST_TIME 48 8474
TEST_TIME 49 8437
TEST_TIME 50 8307
TEST_TIME 51 8275
TEST_TIME 52 8202
TEST_TIME 53 8144
TEST_TIME 54 8099
TEST_TIME 55 8081
TEST_TIME 56 8039
TEST_TIME 57 8015
TEST_TIME 58 7945
TEST_TIME 59 8249
TEST_TIME 60 8203
TEST_TIME 61 8240
TEST_TIME 62 8234
TEST_TIME 63 8216
TEST_TIME 64 8221
TEST_TIME 65 8227
TEST_TIME 66 8279
TEST_TIME 67 8427
TEST_TIME 68 8406
TEST_TIME 69 8464
TEST_TIME 70 8480
TEST_TIME 71 8570
TEST_TIME 72 8554
TEST_TIME 73 8492
TEST_TIME 74 8384
TEST_TIME 75 8335
TEST_TIME 76 8342
TEST_TIME 77 8319
TEST_TIME 78 8260
TEST_TIME 79 8257
TEST_TIME 80 8222
TEST_TIME 81 8171
TEST_TIME 82 8205
TEST_TIME 83 8268
TEST_TIME 84 8250
TEST_TIME 85 8280
TEST_TIME 86 8259
TEST_TIME 87 8281
TEST_TIME 88 8332
TEST_TIME 89 8299
TEST_TIME 90 8310
TEST_TIME 91 8535
TEST_TIME 92 8510
TEST_TIME 93 8487
TEST_TIME 94 8414
TEST_TIME 95 8354
TEST_TIME 96 8364
TEST_TIME 97 8374
TEST_TIME 98 8315
TEST_TIME 99 8305
TEST_TIME 100 8275
TEST_TIME 101 8270
TEST_TIME 102 8299
TEST_TIME 103 8307
TEST_TIME 104 8319
TEST_TIME 105 8330
TEST_TIME 106 8329
TEST_TIME 107 8371
TEST_TIME 108 8361
TEST_TIME 109 8392
TEST_TIME 110 8427
TEST_TIME 111 8430
TEST_TIME 112 8399
TEST_TIME 113 8335
TEST_TIME 114 8332
TEST_TIME 115 8290
TEST_TIME 116 8285
TEST_TIME 117 8271
TEST_TIME 118 8254
TEST_TIME 119 8268
TEST_TIME 120 8254

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-08-2020 06:52:52 to 15-08-2020 06:52:55.

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 1366564 1366564 0 0.00 0 21709 7 0 0
doc_list_biud 1366564 1366564 0 0.00 0 46019 43 0 0
sp_add_invoice_to_stock 4368 4310 58 1.32 30 184524 22766 0 58
sp_cancel_adding_invoice 5203 5203 0 0.00 5 73432 1027 0 0
sp_cancel_client_order 1492 1492 0 0.00 4 18120 578 0 0
sp_cancel_customer_reserve 509986 509986 0 0.00 1 42458 94 0 0
sp_cancel_pay_from_customer 574 574 0 0.00 3 8204 136 0 0
sp_cancel_pay_to_supplier 328 328 0 0.00 2 7681 147 0 0
sp_cancel_supplier_invoice 5838 5835 3 0.05 2 30292 1160 0 3
sp_cancel_supplier_order 366 366 0 0.00 15 1388776 166616 0 0
sp_cancel_write_off 8549 8549 0 0.00 1 9802 161 0 0
sp_client_order 17948 17948 0 0.00 3 25100 458 0 0
sp_customer_reserve 404877 404877 0 0.00 0 50282 218 0 0
sp_fill_shopping_cart 27631 27631 0 0.00 1 21711 51 0 0
sp_fill_shopping_cart_clo_res 404876 404876 0 0.00 0 30372 25 0 0
sp_get_clo_for_invoice 4309 4309 0 0.00 0 47443 546 0 0
sp_kill_cost_storno 14654 14654 0 0.00 0 9483 94 0 0
sp_kill_qstorno_ret_qs2qd 1034722 1034722 0 0.00 0 41360 51 0 0
sp_kill_qty_storno 544429 544429 0 0.00 0 46018 108 0 0
sp_lock_selected_doc 17922 17922 0 0.00 0 9623 6 0 0
sp_make_cost_storno 24414 24414 0 0.00 0 15426 68 0 0
sp_multiply_rows_for_pdistr 24414 24414 0 0.00 0 8357 37 0 0
sp_multiply_rows_for_qdistr 17948 17948 0 0.00 0 20515 242 0 0
sp_pay_from_customer 6213 6213 0 0.00 4 16864 126 0 0
sp_pay_to_supplier 5203 5203 0 0.00 4 17317 149 0 0
sp_payment_common 11416 11416 0 0.00 1 8860 18 0 0
sp_qd_handle_on_cancel_clo 1492 1492 0 0.00 0 18110 536 0 0
sp_qd_handle_on_invoice_upd_sts 9510 9510 0 0.00 0 46017 481 0 0
sp_qd_handle_on_reserve_upd_sts 8691 8691 0 0.00 0 11899 70 0 0
sp_reserve_write_off 8691 8691 0 0.00 6 15434 217 0 0
sp_supplier_invoice 4847 4847 0 0.00 6 125301 4227 0 0
sp_supplier_order 4858 4858 0 0.00 5 42303 1509 0 0
srv_aggregate_perf_data 78 78 0 0.00 2468 524834 30379 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 678 129731 45185 0 0
srv_find_qd_qs_mism 949348 949348 0 0.00 0 21709 6 0 0
srv_make_invnt_saldo 332 332 0 0.00 58 160264 6233 0 0
srv_make_money_saldo 634 634 0 0.00 1 34943 977 0 0
srv_recalc_idx_stat 4 4 0 0.00 228598 475604 299251 0 0
t$perf-abend:sp_add_invoice_to_stock 58 58 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4310 4310 0 0.00 0 8375 249 0 0
t$perf-norm:sp_cancel_adding_invoice 339 339 0 0.00 0 12277 145 0 0
t$perf-norm:sp_cancel_client_order 1492 1492 0 0.00 0 6212 19 0 0
t$perf-norm:sp_cancel_customer_reserve 601 601 0 0.00 0 951 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 574 574 0 0.00 0 788 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 328 328 0 0.00 0 761 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 1041 23 0 0
t$perf-norm:sp_cancel_supplier_order 366 366 0 0.00 0 26573 2514 0 0
t$perf-norm:sp_cancel_write_off 858 858 0 0.00 0 1326 7 0 0
t$perf-norm:sp_client_order 17948 17948 0 0.00 0 7372 25 0 0
t$perf-norm:sp_customer_reserve 848 848 0 0.00 0 2720 12 0 0
t$perf-norm:sp_pay_from_customer 6213 6213 0 0.00 0 3975 10 0 0
t$perf-norm:sp_pay_to_supplier 5203 5203 0 0.00 0 5661 10 0 0
t$perf-norm:sp_reserve_write_off 8691 8691 0 0.00 0 7162 14 0 0
t$perf-norm:sp_supplier_invoice 4847 4847 0 0.00 0 6812 29 0 0
t$perf-norm:sp_supplier_order 4858 4858 0 0.00 0 3983 21 0 0
t$perf-norm:srv_aggregate_perf_data 78 78 0 0.00 0 525 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 27 1 0 0
t$perf-norm:srv_make_invnt_saldo 332 332 0 0.00 0 1573 10 0 0
t$perf-norm:srv_make_money_saldo 634 634 0 0.00 0 2508 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 99 28 0 0
v_add_invoice_to_stock 8730 8614 116 1.32 0 8132 19 0 116
v_all_customers 32896 32896 0 0.00 0 22 1 0 0
v_all_suppliers 27162 27162 0 0.00 0 24 1 0 0
v_all_wares 35894 35894 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 678 678 0 0.00 0 1340 10 0 0
v_cancel_client_order 2984 2984 0 0.00 0 2541 6 0 0
v_cancel_customer_prepayment 1148 1148 0 0.00 0 3452 13 0 0
v_cancel_customer_reserve 1202 1202 0 0.00 0 2538 10 0 0
v_cancel_payment_to_supplier 656 656 0 0.00 0 3439 17 0 0
v_cancel_supplier_invoice 738 732 6 0.81 0 2128 17 0 6
v_cancel_supplier_order 732 732 0 0.00 0 6235 13 0 0
v_cancel_write_off 1716 1716 0 0.00 0 2660 19 0 0
v_max_id_clo_ord 4857 4857 0 0.00 0 3020 2 0 0
v_max_id_clo_res 848 848 0 0.00 0 3751 20 0 0
v_max_id_ord_sup 4826 4826 0 0.00 0 3687 5 0 0
v_max_non_paid_invoice 1305 1305 0 0.00 0 757 3 0 0
v_max_non_paid_realizn 3178 3178 0 0.00 0 1439 3 0 0
v_min_id_clo_ord 4857 4857 0 0.00 0 3821 6 0 0
v_min_id_clo_res 848 848 0 0.00 0 11347 18 0 0
v_min_id_ord_sup 4826 4826 0 0.00 0 9721 14 0 0
v_min_non_paid_invoice 5203 5203 0 0.00 0 1695 6 0 0
v_min_non_paid_realizn 6213 6213 0 0.00 0 5098 12 0 0
v_our_firm 9068 9068 0 0.00 0 15 1 0 0
v_reserve_write_off 17382 17382 0 0.00 0 9183 7 0 0
x_lock_depdocs_on_canc_invoice 10672 10672 0 0.00 0 10721 128 0 0
x_lock_depdocs_on_canc_sup_ord 366 366 0 0.00 0 5835 477 0 0
x_make_qstorno_1000_1200 4858 4858 0 0.00 1 42295 1415 0 0
x_make_qstorno_1200_2000 4847 4847 0 0.00 3 114820 4019 0 0
x_make_qstorno_2100_3300 404877 404877 0 0.00 0 50280 189 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-08-2020 06:52:56 to 15-08-2020 06:53:00.

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
04:21:52 5 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
04:22:54 38056 8192000000 9720535024 10013552640 794016096 8.836 100 79 157 1 820243296 7591600 18635600
04:24:42 20138 8192000000 9760911680 10061721600 836010416 9.260 100 90 180 0 870698816 13410640 21277760
04:26:08 24002 8192000000 9797614240 10107142144 855798048 9.458 100 89 178 0 907099152 30532192 20768912
04:27:37 23316 8192000000 9834690720 10160664576 893592496 9.835 100 77 150 2 951518512 41110560 16815456
04:29:04 18480 8192000000 9898600480 10237628416 934448240 10.238 100 74 148 1 1009256816 58960544 15848032
04:30:28 6739 8192000000 9939775664 10289295360 968413776 10.571 100 73 144 2 1049999088 66726224 14859088
04:31:41 4158 8192000000 9973576304 10330509312 1004796864 10.925 100 73 145 1 1083020832 63927248 14296720
04:32:46 4282 8192000000 9993786192 10352111616 1033961136 11.207 100 92 185 1 1108099824 55122112 19016576
04:33:54 2230 8192000000 10033058848 10399268864 1052850800 11.388 100 79 158 1 1142602576 73586608 16165168
04:34:58 1877 8192000000 10033080992 10402861056 1068838432 11.541 100 81 161 0 1150844416 65757152 16248832
04:36:03 879 8192000000 10057897248 10427645952 1073169456 11.582 100 93 187 1 1172710672 80978640 18562576
04:37:07 1335 8192000000 10064813920 10444890112 1072625680 11.577 100 74 148 1 1175077760 87264720 15187360
04:38:12 6412 8192000000 9977817680 10375872512 1075903792 11.608 100 29 58 1 1110923824 28498624 6521408
04:39:20 5271 8192000000 10000499296 10390835200 1078458448 11.633 100 35 69 1 1117802864 32165936 7178480
04:40:27 5181 8192000000 10003809920 10399981568 1083416304 11.680 100 29 58 0 1126833408 37172064 6245040
04:41:33 8998 8192000000 9994072880 10394279936 1087480864 11.719 100 23 46 3 1118454336 26372512 4600960
04:42:44 41347 8192000000 10009536992 10403823616 1090394224 11.746 100 50 97 1 1110104816 8857600 10852992
04:44:27 4652 8192000000 10006295424 10398339072 1074612672 11.596 99 40 79 1 1113242576 29713120 8916784
04:45:32 10740 8192000000 9988213248 10378694656 1054487984 11.404 98 26 53 3 1074856528 14571136 5797408
04:46:48 7550 8192000000 9927700832 10304901120 972195120 10.608 94 33 67 4 990301024 10901856 7204048
04:47:56 19058 8192000000 9940254080 10327040000 986225536 10.745 96 38 77 6 1015308608 21415520 7667552
04:49:18 6990 8192000000 9949683808 10324430848 1003779312 10.915 98 52 101 4 1048461344 33986960 10695072
04:50:27 8114 8192000000 9947937744 10331996160 1031341536 11.181 100 39 76 3 1046910640 7497840 8071264
04:51:37 60627 8192000000 9986056944 10371022848 1067747744 11.531 100 42 81 3 1090645088 14033472 8863872
04:53:54 25076 8192000000 10041285504 10428338176 1082124336 11.668 100 53 105 0 1152565776 60207040 10234400
04:55:38 6737 8192000000 10056868960 10443939840 1108368992 11.917 100 70 139 1 1159879536 36895040 14615504
04:56:52 32891 8192000000 10133818368 10528305152 1124575184 12.070 100 60 120 0 1235058032 98065536 12417312
04:58:32 8252 8192000000 10134575088 10533605376 1135027504 12.169 100 48 96 1 1244248192 97979552 11241136
04:59:47 26355 8192000000 10155843888 10571661312 1133808736 12.157 100 48 95 4 1282798240 137752832 11236672
05:01:19 14575 8192000000 10203643888 10605985792 1151896672 12.327 100 59 115 4 1310793008 146226336 12670000
05:02:37 18978 8192000000 10258435360 10660589568 1157424240 12.379 100 57 114 1 1364283088 194189072 12669776
05:04:08 3503 8192000000 10296827424 10698092544 1173965392 12.534 100 69 138 0 1399897200 210906864 15024944
05:05:14 4609 8192000000 10325115120 10730278912 1182032816 12.609 100 79 153 2 1439225696 240246000 16946880
05:06:26 4897 8192000000 10364316960 10775474176 1193804000 12.719 100 71 140 3 1474270112 263991840 16474272
05:07:38 6111 8192000000 10422458720 10842595328 1197285680 12.751 100 74 149 1 1522473312 307928736 17258896
05:08:52 18462 8192000000 10411500640 10841223168 1185433360 12.641 99 52 104 2 1518766352 319718448 13614544
05:10:19 4798 8192000000 10460288784 10878443520 1192101360 12.703 100 85 171 0 1558840288 346543728 20195200
05:11:31 3173 8192000000 10442178688 10849144832 1209538560 12.865 100 75 146 1 1537289296 309899168 17851568
05:12:42 2801 8192000000 10509174960 10911182848 1195693984 12.736 100 81 161 1 1606724032 391809968 19220080
05:13:54 1304 8192000000 10538076416 10943897600 1203787520 12.811 100 93 186 1 1632757296 407038544 21931232
05:15:08 2578 8192000000 10577025344 10990845952 1191038544 12.693 100 91 183 1 1676990336 463109792 22842000
05:16:28 675 8192000000 10612329856 11013009408 1174252608 12.537 100 90 180 2 1701971008 506165552 21552848
05:17:38 920 8192000000 10617817088 11024949248 1175297584 12.546 100 80 160 1 1710877872 514413216 21167072
05:18:46 7764 8192000000 10506183360 10915954688 1171960192 12.515 100 62 122 0 1613519520 426441344 15117984
05:20:00 8484 8192000000 10536775584 10973925376 1169862016 12.496 100 50 100 0 1638371040 455646528 12862496
05:21:36 25112 8192000000 10451333904 10909884416 1131391088 12.134 99 26 52 1 1548606848 409871600 7344160
05:23:08 12464 8192000000 10313676816 10847576064 1134428256 12.163 100 26 52 0 1430705216 289498528 6778432
05:24:39 11255 8192000000 10164748736 10633195520 1151149456 12.320 100 38 76 1 1257186752 98091296 7946000
05:26:35 46015 8192000000 10088721376 10601783296 1118586224 12.014 98 12 22 1 1205198320 84445360 2166736
05:28:24 42303 8192000000 10071752528 10511147008 1121775344 12.044 99 30 56 0 1156928032 29765152 5387536
05:30:09 54312 8192000000 10076449632 10516606976 1126923968 12.092 100 40 80 1 1174920368 39840656 8155744
05:32:04 45220 8192000000 10085939376 10539917312 1120911632 12.036 99 20 40 2 1193403120 68355520 4135968
05:34:07 111551 8192000000 10058116816 10506522624 1090377584 11.746 97 19 38 2 1133160976 39100512 3682880
05:37:01 60161 8192000000 10067070640 10517630976 1103545952 11.871 98 16 32 1 1150627728 43769952 3311824
05:39:05 120462 8192000000 10060988624 10534322176 1089691056 11.740 98 25 49 1 1173575888 77330848 6553984
05:42:56 32535 8192000000 10041644768 10475134976 1116514256 11.994 100 26 52 0 1155069136 33376832 5178048
05:44:35 17727 8192000000 10083915696 10517094400 1120036048 12.027 100 44 84 2 1188111024 58671824 9403152
05:46:13 53702 8192000000 10151000800 10600103936 1145870192 12.271 100 32 64 0 1268495664 115432288 7193184
05:48:59 74090 8192000000 10142115376 10619248640 1118349536 12.011 99 16 32 0 1260180624 137785728 4045360
05:51:35 111942 8192000000 10059125472 10500325376 1082949424 11.676 96 14 28 0 1127989584 42063760 2976400
05:54:42 49934 8192000000 10069240976 10504433664 1117968176 12.008 99 27 53 1 1173570336 49334288 6267872
05:56:35 54149 8192000000 10171580224 10648600576 1114295744 11.973 98 44 88 1 1229456528 106163600 8997184
05:58:35 48365 8192000000 10084224848 10550169600 1131337024 12.134 100 22 44 0 1171713040 36103248 4272768
06:00:27 78528 8192000000 10096633696 10538606592 1128596608 12.108 99 21 42 1 1201209296 68249232 4363456
06:02:47 92572 8192000000 10075169456 10553479168 1120639872 12.033 98 12 23 4 1170149232 46868384 2640976
06:06:18 35657 8192000000 10080598736 10526580736 1131275824 12.133 99 15 30 1 1172743648 38411936 3055888
06:07:55 69248 8192000000 10095084336 10584465408 1111763568 11.949 97 11 22 0 1178826864 64611296 2452000
06:10:05 55938 8192000000 10095428448 10559840256 1136198320 12.180 100 15 30 1 1198502528 58200096 4104112
06:12:01 53635 8192000000 10119718064 10559942656 1141184576 12.227 100 21 42 1 1224175792 78416352 4574864
06:14:09 82017 8192000000 10061650000 10505916416 1099902304 11.837 97 10 20 1 1171141840 68605232 2634304
06:16:37 44490 8192000000 10069965968 10503053312 1100003552 11.838 97 18 36 1 1151870928 48091888 3775488
06:18:23 81290 8192000000 10061504864 10481586176 1110747504 11.939 98 22 42 3 1176143776 60529680 4866592
06:20:48 91510 8192000000 10157726128 10661519360 1093271184 11.774 96 24 48 1 1245752928 147002992 5478752
06:23:22 53533 8192000000 10056128976 10490490880 1090204976 11.745 97 16 32 4 1133803264 40343712 3254576
06:25:16 129731 8192000000 10115490208 10565410816 1111977728 11.951 99 18 36 0 1211021168 95277120 3766320
06:28:39 82930 8192000000 10063046224 10534240256 1097040096 11.810 98 5 10 1 1149463440 51433552 989792
06:31:02 52323 8192000000 10078905248 10504273920 1100921232 11.846 98 15 28 0 1147795584 44202336 2672016
06:32:55 69362 8192000000 10071584272 10515501056 1082236384 11.669 97 23 46 1 1155055008 68023376 4795248
06:35:10 63314 8192000000 10050052480 10513850368 1078842960 11.636 97 14 28 0 1145379040 63699264 2836816
06:37:22 76722 8192000000 10063082592 10534371328 1100326160 11.841 98 37 74 1 1163570512 55646672 7597680
06:39:43 36628 8192000000 10103689456 10523291648 1116129536 11.990 100 40 79 2 1172254992 47932864 8192592
06:41:22 78666 8192000000 10066336272 10485559296 1055621744 11.415 94 28 54 2 1095664928 34213824 5829360
06:43:43 126337 8192000000 10026048256 10475466752 1098622080 11.825 99 28 56 0 1161576208 57478160 5475968
06:46:53 77653 8192000000 10037262992 10518892544 1109355008 11.926 99 5 10 0 1144748464 34421856 971600
06:49:11 35390 8192000000 10054415008 10493673472 1099295888 11.831 98 24 48 2 1153166848 48800496 5070464
06:50:54 44225 8192000000 10060735696 10489999360 1109691984 11.930 99 24 49 2 1143262672 27895520 5675168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 15-08-2020 06:53:02 to 15-08-2020 06:53:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 58 v_add_invoice_to_stock 335544517
user_exc 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 15-08-2020 06:53:17 to 15-08-2020 06:53:18.

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          206563
MON$OLDEST_ACTIVE               208902
MON$OLDEST_SNAPSHOT             208902
MON$NEXT_TRANSACTION            208902
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                       2058144
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             668
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2154 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2154 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2154 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.2154 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 ---
:25: 
:26: mscs-test-161.budzdorov.ru    Sat Aug 15 06:51:53 2020
:27:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:28: 
--- end of diff output ---

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

15.08.20 06:53:20. 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-15 06:53:23 NO_SEVERE_ERRORS_FOUND
15.08.20 06:53:25. End of report.