Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.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 16707412 kB
MemAvailable 30950336 kB
Buffers 0 kB
Cached 14936092 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-14 06:51:48.2220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/44358
TRN_ID                          211183
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: 8324.83 26794 998979
customer order: creation 160.01 439 19201
customer order: refuse 13.51 486 1621
order to supplier: creation 43.48 1429 5217
order to supplier: removal 3.02 157133 362
invoice (draft): creation 42.74 4431 5129
invoice (draft): removal 46.75 1016 5610
invoice accept: apply 38.19 22057 4583
invoice accept: cancel 42.31 1101 5077
customer reserve: creation 3558.33 210 426999
customer reserve: removal 4103.79 92 492455
realization accept: apply 76.69 222 9203
realization accept: cancel 77.82 168 9338
payment from customer: creation 55.08 119 6610
payment from customer: removal 5.48 146 658
payment to supplier: creation 44.54 146 5345
payment to supplier: removal 3.18 123 382
service: total inventory turnovers 3.23 5453 387
service: total monetary turnovers 5.73 983 687
service: aggregate perf. data 0.93 15527 111
service: refresh index statistics 0.03 324600 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-08-2020 06:52:56 to 14-08-2020 06:52:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1156
TEST_TIME 2 2572
TEST_TIME 3 2470
TEST_TIME 4 3557
TEST_TIME 5 3650
TEST_TIME 6 3494
TEST_TIME 7 3630
TEST_TIME 8 3704
TEST_TIME 9 3562
TEST_TIME 10 3785
TEST_TIME 11 3871
TEST_TIME 12 4008
TEST_TIME 13 4479
TEST_TIME 14 4629
TEST_TIME 15 4800
TEST_TIME 16 4916
TEST_TIME 17 5303
TEST_TIME 18 5383
TEST_TIME 19 5636
TEST_TIME 20 5662
TEST_TIME 21 6148
TEST_TIME 22 6330
TEST_TIME 23 6412
TEST_TIME 24 6592
TEST_TIME 25 6585
TEST_TIME 26 6961
TEST_TIME 27 7162
TEST_TIME 28 7221
TEST_TIME 29 7229
TEST_TIME 30 7398
TEST_TIME 31 7559
TEST_TIME 32 7695
TEST_TIME 33 7865
TEST_TIME 34 8244
TEST_TIME 35 8542
TEST_TIME 36 9234
TEST_TIME 37 9560
TEST_TIME 38 9705
TEST_TIME 39 9525
TEST_TIME 40 9392
TEST_TIME 41 9371
TEST_TIME 42 9324
TEST_TIME 43 9222
TEST_TIME 44 9121
TEST_TIME 45 9050
TEST_TIME 46 9064
TEST_TIME 47 8993
TEST_TIME 48 8968
TEST_TIME 49 8880
TEST_TIME 50 8769
TEST_TIME 51 8682
TEST_TIME 52 8561
TEST_TIME 53 8516
TEST_TIME 54 8450
TEST_TIME 55 8509
TEST_TIME 56 8475
TEST_TIME 57 8407
TEST_TIME 58 8408
TEST_TIME 59 8317
TEST_TIME 60 8273
TEST_TIME 61 8302
TEST_TIME 62 8239
TEST_TIME 63 8315
TEST_TIME 64 8298
TEST_TIME 65 8340
TEST_TIME 66 8356
TEST_TIME 67 8455
TEST_TIME 68 8391
TEST_TIME 69 8407
TEST_TIME 70 8456
TEST_TIME 71 8483
TEST_TIME 72 8455
TEST_TIME 73 8516
TEST_TIME 74 8543
TEST_TIME 75 8475
TEST_TIME 76 8373
TEST_TIME 77 8326
TEST_TIME 78 8335
TEST_TIME 79 8284
TEST_TIME 80 8252
TEST_TIME 81 8268
TEST_TIME 82 8284
TEST_TIME 83 8283
TEST_TIME 84 8317
TEST_TIME 85 8299
TEST_TIME 86 8337
TEST_TIME 87 8292
TEST_TIME 88 8314
TEST_TIME 89 8368
TEST_TIME 90 8453
TEST_TIME 91 8505
TEST_TIME 92 8497
TEST_TIME 93 8421
TEST_TIME 94 8398
TEST_TIME 95 8375
TEST_TIME 96 8354
TEST_TIME 97 8372
TEST_TIME 98 8366
TEST_TIME 99 8338
TEST_TIME 100 8329
TEST_TIME 101 8377
TEST_TIME 102 8365
TEST_TIME 103 8358
TEST_TIME 104 8362
TEST_TIME 105 8357
TEST_TIME 106 8459
TEST_TIME 107 8485
TEST_TIME 108 8479
TEST_TIME 109 8524
TEST_TIME 110 8463
TEST_TIME 111 8397
TEST_TIME 112 8358
TEST_TIME 113 8321
TEST_TIME 114 8325
TEST_TIME 115 8311
TEST_TIME 116 8300
TEST_TIME 117 8303
TEST_TIME 118 8305
TEST_TIME 119 8336
TEST_TIME 120 8328

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-08-2020 06:53:00 to 14-08-2020 06:53:03.

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 1392953 1392953 0 0.00 0 18483 8 0 0
doc_list_biud 1392953 1392953 0 0.00 0 44650 40 0 0
sp_add_invoice_to_stock 4697 4583 114 2.42 36 197170 22058 0 114
sp_cancel_adding_invoice 5077 5077 0 0.00 4 97916 1101 0 0
sp_cancel_client_order 1621 1621 0 0.00 4 8492 487 0 0
sp_cancel_customer_reserve 492455 492455 0 0.00 0 33239 92 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 2 5309 147 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 2 4087 123 0 0
sp_cancel_supplier_invoice 5617 5610 7 0.12 1 24544 1017 0 7
sp_cancel_supplier_order 362 362 0 0.00 6 1549257 157134 0 0
sp_cancel_write_off 9338 9338 0 0.00 1 10142 169 0 0
sp_client_order 19201 19201 0 0.00 3 24290 440 0 0
sp_customer_reserve 426999 426999 0 0.00 0 46706 211 0 0
sp_fill_shopping_cart 29538 29538 0 0.00 1 11829 50 0 0
sp_fill_shopping_cart_clo_res 426991 426991 0 0.00 0 43026 23 0 0
sp_get_clo_for_invoice 4575 4575 0 0.00 0 40702 476 0 0
sp_kill_cost_storno 15455 15455 0 0.00 0 8363 98 0 0
sp_kill_qstorno_ret_qs2qd 1000220 1000220 0 0.00 0 44649 49 0 0
sp_kill_qty_storno 528241 528241 0 0.00 0 44650 104 0 0
sp_lock_selected_doc 19109 19109 0 0.00 0 9131 7 0 0
sp_make_cost_storno 25733 25733 0 0.00 0 15056 74 0 0
sp_multiply_rows_for_pdistr 25733 25733 0 0.00 0 9148 36 0 0
sp_multiply_rows_for_qdistr 19201 19201 0 0.00 0 21955 229 0 0
sp_pay_from_customer 6610 6610 0 0.00 3 11088 119 0 0
sp_pay_to_supplier 5345 5345 0 0.00 4 18492 147 0 0
sp_payment_common 11955 11955 0 0.00 1 8517 18 0 0
sp_qd_handle_on_cancel_clo 1621 1621 0 0.00 0 7604 444 0 0
sp_qd_handle_on_invoice_upd_sts 9652 9652 0 0.00 0 16059 460 0 0
sp_qd_handle_on_reserve_upd_sts 9203 9203 0 0.00 0 15575 61 0 0
sp_reserve_write_off 9203 9203 0 0.00 6 15586 222 0 0
sp_supplier_invoice 5129 5129 0 0.00 6 111307 4431 0 0
sp_supplier_order 5217 5217 0 0.00 5 30017 1430 0 0
srv_aggregate_perf_data 111 111 0 0.00 2056 213112 15528 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1291 132036 42805 0 0
srv_find_qd_qs_mism 954108 954108 0 0.00 0 18060 7 0 0
srv_make_invnt_saldo 387 387 0 0.00 38 163189 5454 0 0
srv_make_money_saldo 687 687 0 0.00 1 19301 984 0 0
srv_recalc_idx_stat 4 4 0 0.00 137414 440662 324601 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4583 4583 0 0.00 0 7185 261 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 1 5164 153 0 0
t$perf-norm:sp_cancel_client_order 1621 1621 0 0.00 0 3255 18 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 1774 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 1052 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 918 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 355 355 0 0.00 0 811 21 0 0
t$perf-norm:sp_cancel_supplier_order 362 362 0 0.00 0 57003 2692 0 0
t$perf-norm:sp_cancel_write_off 901 901 0 0.00 0 5189 19 0 0
t$perf-norm:sp_client_order 19201 19201 0 0.00 0 13166 25 0 0
t$perf-norm:sp_customer_reserve 914 914 0 0.00 0 2671 8 0 0
t$perf-norm:sp_pay_from_customer 6610 6610 0 0.00 0 4087 10 0 0
t$perf-norm:sp_pay_to_supplier 5345 5345 0 0.00 0 10411 11 0 0
t$perf-norm:sp_reserve_write_off 9203 9203 0 0.00 0 5213 11 0 0
t$perf-norm:sp_supplier_invoice 5129 5129 0 0.00 0 1970 23 0 0
t$perf-norm:sp_supplier_order 5217 5217 0 0.00 0 10335 25 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 1249 41 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 42 1 0 0
t$perf-norm:srv_make_invnt_saldo 387 387 0 0.00 0 706 8 0 0
t$perf-norm:srv_make_money_saldo 687 687 0 0.00 0 1364 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 9378 9150 228 2.43 0 4185 18 0 228
v_all_customers 35258 35258 0 0.00 0 97 1 0 0
v_all_suppliers 28414 28414 0 0.00 0 93 1 0 0
v_all_wares 38402 38402 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 4867 17 0 0
v_cancel_client_order 3242 3242 0 0.00 0 5792 10 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 1162 8 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 2584 7 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 2820 13 0 0
v_cancel_supplier_invoice 724 710 14 1.93 0 4777 24 0 14
v_cancel_supplier_order 724 724 0 0.00 0 580 5 0 0
v_cancel_write_off 1802 1802 0 0.00 0 5533 20 0 0
v_max_id_clo_ord 5215 5215 0 0.00 0 1201 2 0 0
v_max_id_clo_res 914 914 0 0.00 0 8396 28 0 0
v_max_id_ord_sup 5122 5122 0 0.00 0 6026 4 0 0
v_max_non_paid_invoice 1475 1475 0 0.00 0 224 2 0 0
v_max_non_paid_realizn 3387 3387 0 0.00 0 3085 4 0 0
v_min_id_clo_ord 5215 5215 0 0.00 0 3847 10 0 0
v_min_id_clo_res 914 914 0 0.00 0 4900 26 0 0
v_min_id_ord_sup 5122 5122 0 0.00 0 7271 11 0 0
v_min_non_paid_invoice 5345 5345 0 0.00 0 5731 11 0 0
v_min_non_paid_realizn 6610 6610 0 0.00 0 5188 9 0 0
v_our_firm 9590 9590 0 0.00 0 18 1 0 0
v_reserve_write_off 18406 18406 0 0.00 0 5814 9 0 0
x_lock_depdocs_on_canc_invoice 10332 10332 0 0.00 0 22476 174 0 0
x_lock_depdocs_on_canc_sup_ord 362 362 0 0.00 0 27716 598 0 0
x_make_qstorno_1000_1200 5217 5217 0 0.00 1 30006 1326 0 0
x_make_qstorno_1200_2000 5129 5129 0 0.00 3 109160 4242 0 0
x_make_qstorno_2100_3300 426999 426999 0 0.00 0 42378 184 0 0

Completed "Performance in DETAILS". Done for 00:00:03, from 14-08-2020 06:53:04 to 14-08-2020 06:53:07.

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:51 2 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
04:22:52 55662 8192000000 9732354320 10026844160 796419600 8.860 100 90 180 1 826551648 8014240 22117808
04:24:52 31544 8192000000 9769512160 10071519232 837173072 9.271 100 89 179 1 871492464 13622192 20697200
04:26:30 20561 8192000000 9802297312 10115112960 866480544 9.565 100 84 168 0 904730336 19088480 19161312
04:27:57 16467 8192000000 9862620752 10192171008 915124240 10.048 100 79 158 1 974990992 42792736 17074016
04:29:18 14270 8192000000 9906011808 10248556544 949810112 10.389 100 78 156 0 1020403456 54219392 16373952
04:30:38 7857 8192000000 9946283056 10295697408 987149376 10.754 100 74 148 0 1060420928 57901152 15370400
04:31:50 4293 8192000000 9995054848 10349264896 1005314048 10.930 100 73 146 0 1107082000 87290240 14477712
04:32:57 5646 8192000000 9995275584 10353463296 1026326848 11.133 100 84 168 4 1112851392 69928448 16596096
04:34:06 2869 8192000000 10017781440 10377220096 1036025856 11.226 100 93 186 0 1129512400 74017472 19469072
04:35:11 2153 8192000000 10025739312 10389471232 1046166352 11.324 100 86 172 2 1136975632 73511104 17298176
04:36:19 1454 8192000000 10035108416 10401107968 1050303008 11.364 100 85 170 0 1143917488 74411424 19203056
04:37:25 1718 8192000000 10041314224 10409005056 1058629648 11.443 100 92 185 1 1156511248 78655712 19225888
04:38:30 10105 8192000000 10031699296 10407657472 1068192992 11.535 100 74 149 1 1146035952 62706160 15136800
04:39:43 4754 8192000000 10008265744 10388267008 1075634144 11.606 100 56 112 2 1118031232 31228080 11169008
04:40:51 3524 8192000000 10018085264 10407481344 1080712624 11.654 100 45 89 1 1130988096 41006208 9269264
04:41:56 48970 8192000000 10006152672 10395922432 1086707056 11.711 100 43 85 1 1106628880 9850880 10070944
04:43:49 4829 8192000000 10032573520 10436845568 1087847504 11.722 100 45 90 0 1140786352 43749840 9189008
04:44:57 5876 8192000000 9997685360 10408407040 1082358624 11.670 100 20 40 0 1131597008 45270192 3968192
04:46:08 21995 8192000000 9981412208 10376417280 1022897584 11.100 95 20 39 2 1054470752 27370944 4202224
04:47:33 4079 8192000000 9993411648 10369413120 1046753824 11.330 99 63 125 1 1095015392 34353360 13908208
04:48:39 6648 8192000000 9909364496 10270666752 977104832 10.656 95 51 103 6 1004509168 14052384 13351952
04:49:50 14157 8192000000 9927961984 10319745024 976868032 10.654 95 25 50 0 1004855136 22246128 5740976
04:51:06 3823 8192000000 9947447168 10319921152 1014784688 11.022 98 38 76 2 1040571360 17277424 8509248
04:52:19 42102 8192000000 9963860336 10346549248 1045138896 11.314 100 35 70 1 1071812832 19314192 7359744
04:54:12 64844 8192000000 10061356480 10464620544 1064204560 11.497 99 53 106 2 1168128960 92465120 11459280
04:56:36 24751 8192000000 10063827328 10463825920 1095016832 11.790 100 25 50 3 1177985008 77668848 5299328
04:58:06 18322 8192000000 10122524128 10517012480 1108844448 11.921 100 42 80 3 1239899872 121246016 9809408
04:59:30 5919 8192000000 10147199712 10543271936 1116636784 11.995 100 48 94 1 1253310352 125871872 10801696
05:00:41 33121 8192000000 10203258272 10601041920 1127167280 12.095 100 57 114 0 1305164704 165625856 12371568
05:02:22 7393 8192000000 10221457664 10622275584 1141237984 12.227 100 55 110 0 1321980848 168460096 12282768
05:03:36 6628 8192000000 10284955376 10687238144 1151850256 12.327 100 59 118 1 1403383616 237507584 14025776
05:04:52 3565 8192000000 10286923264 10692677632 1162064128 12.423 100 66 131 2 1395759392 219130000 14565264
05:06:03 4596 8192000000 10338489536 10738163712 1171618064 12.512 100 81 162 1 1435846688 246078848 18149776
05:07:16 3722 8192000000 10366670032 10767638528 1169355504 12.491 100 81 161 2 1461990960 274507680 18127776
05:08:26 5211 8192000000 10355812576 10780905472 1172298112 12.518 100 72 143 0 1460724384 272773936 15652336
05:09:36 2461 8192000000 10385473920 10787258368 1176739616 12.560 100 84 168 1 1490128768 294395184 18993968
05:10:43 8323 8192000000 10402577424 10808745984 1173086016 12.526 100 74 145 1 1498262832 308684576 16492240
05:11:56 5399 8192000000 10451021744 10878402560 1175536096 12.549 100 76 153 0 1560174848 366569776 18068976
05:13:06 4613 8192000000 10426138000 10836594688 1175581136 12.549 100 73 146 2 1524565568 332026912 16957520
05:14:20 2129 8192000000 10422846288 10829934592 1180040688 12.591 100 89 178 1 1515628576 314374912 21212976
05:15:30 3406 8192000000 10449643568 10857070592 1174332640 12.537 100 71 142 0 1544221520 352879488 17009392
05:16:41 1290 8192000000 10472806688 10908426240 1187821712 12.663 100 94 186 3 1566040960 356330864 21888384
05:17:51 2195 8192000000 10437519568 10876170240 1188379312 12.668 100 90 180 0 1536922016 327563616 20979088
05:19:05 3739 8192000000 10489701120 10901471232 1179817136 12.588 100 69 138 1 1586415664 389783568 16814960
05:20:22 3860 8192000000 10475743984 10891218944 1167239184 12.471 100 61 122 0 1575027184 392943488 14844512
05:21:33 6804 8192000000 10500212304 10916708352 1170222704 12.499 100 60 120 0 1588032336 403392752 14416880
05:22:49 9482 8192000000 10497643872 10968748032 1172696432 12.522 100 42 84 0 1618044480 434610096 10737952
05:24:14 9533 8192000000 10484087648 10960752640 1166090304 12.460 99 41 82 0 1591554960 415047872 10416784
05:25:30 17659 8192000000 10460210784 10930667520 1170994848 12.506 100 35 70 3 1629151360 449075232 9081280
05:27:09 21586 8192000000 10321825248 10834804736 1136457952 12.182 100 19 38 0 1423807488 282436032 4913504
05:28:33 56896 8192000000 10070620976 10591793152 1133297408 12.152 100 16 32 0 1253451120 116594848 3558864
05:30:49 7819 8192000000 10095098848 10513969152 1136333680 12.181 100 69 139 0 1182036592 30381968 15320944
05:32:05 35572 8192000000 10112702176 10561740800 1137084368 12.188 100 41 81 3 1220804112 75784752 7934992
05:34:02 132036 8192000000 10064361232 10522255360 1085976528 11.704 95 10 20 1 1109284832 21434448 1873856
05:37:15 48663 8192000000 10070309968 10514731008 1126869168 12.092 100 12 24 0 1188432688 58899888 2663632
05:39:14 76214 8192000000 10079546240 10553839616 1098041488 11.819 97 19 38 0 1172504288 70410624 4052176
05:42:39 66845 8192000000 10028376192 10472722432 1076608752 11.615 96 36 70 1 1101622320 18669840 6343728
05:45:28 50022 8192000000 10063874240 10511347712 1080069120 11.648 98 47 94 1 1153738224 61891616 11777488
05:47:21 48730 8192000000 10056189616 10513346560 1104212768 11.878 100 15 30 0 1170774096 63385888 3175440
05:49:10 57889 8192000000 10058962192 10539966464 1115015536 11.980 100 16 31 1 1165846944 47493472 3337936
05:51:16 92815 8192000000 10037844592 10515206144 1099025904 11.828 98 11 22 0 1131387104 30510896 1850304
05:53:50 111888 8192000000 10049009984 10476998656 1068345392 11.536 95 9 18 0 1095071744 25277184 1449168
05:56:48 76874 8192000000 10102168816 10561150976 1127872992 12.101 100 23 46 0 1183984736 51503216 4608528
05:59:12 27939 8192000000 10071542768 10494345216 1113031248 11.961 99 45 91 5 1151446448 29340976 9074224
06:00:49 51750 8192000000 10086821424 10563629056 1108722656 11.920 98 27 54 0 1179500384 65365088 5412640
06:02:49 34181 8192000000 10060772176 10491613184 1121266512 12.039 99 29 58 0 1158658320 32220992 5170816
06:04:25 71133 8192000000 10083634832 10519310336 1097781472 11.817 97 19 38 0 1124201504 22721888 3698144
06:06:44 117158 8192000000 10092227856 10535669760 1117625376 12.005 99 38 75 1 1179122192 53544064 7952752
06:09:44 50950 8192000000 10074864128 10567962624 1105747984 11.892 98 8 16 0 1183842304 76134096 1960224
06:11:36 49141 8192000000 10073849376 10528067584 1104922688 11.884 98 16 32 0 1155803680 47443568 3437424
06:13:28 65461 8192000000 10074399328 10508300288 1065151376 11.506 94 12 24 0 1118619376 50901664 2566336
06:16:02 44583 8192000000 10066806176 10497753088 1085561264 11.700 97 29 57 3 1129520480 37779760 6179456
06:17:56 59123 8192000000 10080249184 10503012352 1100323888 11.841 99 24 47 1 1135796768 30976400 4496480
06:19:56 48504 8192000000 10126193712 10572120064 1105868592 11.893 99 11 22 1 1195507744 87111808 2527344
06:21:46 110562 8192000000 10078567424 10522701824 1091075696 11.753 97 8 16 0 1119874896 27418416 1380784
06:24:44 70156 8192000000 10064719872 10527371264 1115868304 11.988 99 23 46 1 1164117824 43810992 4438528
06:26:59 68237 8192000000 10084025264 10568331264 1119370544 12.021 99 4 8 0 1174383664 53903856 1109264
06:29:08 58887 8192000000 10064216256 10506829824 1070764256 11.559 95 13 26 1 1116482608 43728432 1989920
06:31:08 26498 8192000000 10099713872 10538377216 1118693168 12.015 99 30 58 3 1190093232 65431792 5968272
06:32:38 62231 8192000000 10054138592 10489905152 1090951984 11.752 97 25 46 3 1164855536 68832464 5071088
06:34:51 41523 8192000000 10084959104 10529071104 1109330320 11.926 98 42 83 3 1169181456 50405520 9445616
06:36:34 65670 8192000000 10125163648 10586169344 1084046944 11.686 98 21 42 2 1191898752 103254320 4597488
06:38:51 58754 8192000000 10062988384 10492665856 1093760224 11.778 98 20 40 1 1146365648 48602512 4002912
06:40:56 131362 8192000000 10057489184 10508918784 1086747280 11.712 98 17 34 0 1139277568 49196944 3333344
06:44:12 57853 8192000000 10083448896 10538450944 1100401248 11.841 99 12 24 0 1155773280 52834752 2537280
06:46:10 47479 8192000000 10064126336 10525999104 1089383712 11.737 98 13 26 1 1147508240 55864032 2260496
06:47:59 112309 8192000000 10106672192 10560360448 1088812048 11.731 97 18 36 0 1154561744 61801936 3947760
06:50:51 65848 8192000000 9168316912 9473597440 293410096 3.457 26 6 12 1 344609488 49954608 1244784

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-08-2020 06:53:09 to 14-08-2020 06:53:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 114 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 14-08-2020 06:53:24 to 14-08-2020 06:53:25.

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          208448
MON$OLDEST_ACTIVE               211483
MON$OLDEST_SNAPSHOT             211483
MON$NEXT_TRANSACTION            211483
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                       2056992
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             669
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2151 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2151 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2151 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.2151 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 ---
--- end of diff output ---

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

14.08.20 06:53:27. 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-14 06:53:30 NO_SEVERE_ERRORS_FOUND
14.08.20 06:53:32. End of report.