Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.08.2020 21: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 17039656 kB
MemAvailable 30963116 kB
Buffers 0 kB
Cached 14633224 kB
SwapCached 6180 kB
Command: fdisk -l

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=fbc, val=/opt/fb30/bin
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=report_compress_cmd, val=/usr/bin/7za
param=host, val=localhost
param=port, val=3333
param=usr, val=SYSDBA
param=pwd, val=masterkey
param=tmpdir, val=/var/tmp/logs-oltp30
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/oltp30-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 21:51:56.8580
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/51682
TRN_ID                          215665
ATT_ID                          565
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: 8658.58 26188 1039030
customer order: creation 150.43 433 18051
customer order: refuse 12.84 543 1541
order to supplier: creation 40.83 1647 4899
order to supplier: removal 3.28 160187 394
invoice (draft): creation 39.55 4536 4746
invoice (draft): removal 50.60 1220 6072
invoice accept: apply 37.24 25384 4469
invoice accept: cancel 45.65 1078 5478
customer reserve: creation 3496.49 244 419579
customer reserve: removal 4513.74 89 541649
realization accept: apply 72.61 255 8713
realization accept: cancel 81.63 157 9795
payment from customer: creation 51.48 177 6177
payment from customer: removal 5.21 118 625
payment to supplier: creation 42.70 192 5124
payment to supplier: removal 3.08 108 369
service: total inventory turnovers 4.39 1426 527
service: total monetary turnovers 6.03 73 724
service: aggregate perf. data 0.78 23365 94
service: refresh index statistics 0.03 302522 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-08-2020 21:52:50 to 15-08-2020 21:52:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2822
TEST_TIME 2 2929
TEST_TIME 3 3634
TEST_TIME 4 3756
TEST_TIME 5 3580
TEST_TIME 6 3325
TEST_TIME 7 3749
TEST_TIME 8 4093
TEST_TIME 9 3927
TEST_TIME 10 3950
TEST_TIME 11 3977
TEST_TIME 12 3880
TEST_TIME 13 3873
TEST_TIME 14 3825
TEST_TIME 15 3926
TEST_TIME 16 4213
TEST_TIME 17 4180
TEST_TIME 18 4251
TEST_TIME 19 4255
TEST_TIME 20 4495
TEST_TIME 21 4431
TEST_TIME 22 4588
TEST_TIME 23 4706
TEST_TIME 24 4849
TEST_TIME 25 4990
TEST_TIME 26 5152
TEST_TIME 27 5414
TEST_TIME 28 5646
TEST_TIME 29 5669
TEST_TIME 30 6052
TEST_TIME 31 6203
TEST_TIME 32 6649
TEST_TIME 33 6849
TEST_TIME 34 7064
TEST_TIME 35 7259
TEST_TIME 36 7471
TEST_TIME 37 8739
TEST_TIME 38 8604
TEST_TIME 39 8463
TEST_TIME 40 8354
TEST_TIME 41 8308
TEST_TIME 42 8302
TEST_TIME 43 8303
TEST_TIME 44 8220
TEST_TIME 45 8099
TEST_TIME 46 8018
TEST_TIME 47 8077
TEST_TIME 48 8237
TEST_TIME 49 8251
TEST_TIME 50 8215
TEST_TIME 51 8206
TEST_TIME 52 8102
TEST_TIME 53 7972
TEST_TIME 54 7921
TEST_TIME 55 7872
TEST_TIME 56 7860
TEST_TIME 57 7889
TEST_TIME 58 7778
TEST_TIME 59 7861
TEST_TIME 60 7887
TEST_TIME 61 7905
TEST_TIME 62 8012
TEST_TIME 63 8025
TEST_TIME 64 8094
TEST_TIME 65 8057
TEST_TIME 66 8088
TEST_TIME 67 8261
TEST_TIME 68 8250
TEST_TIME 69 8344
TEST_TIME 70 8367
TEST_TIME 71 8422
TEST_TIME 72 8449
TEST_TIME 73 8398
TEST_TIME 74 8340
TEST_TIME 75 8285
TEST_TIME 76 8410
TEST_TIME 77 8333
TEST_TIME 78 8289
TEST_TIME 79 8343
TEST_TIME 80 8327
TEST_TIME 81 8312
TEST_TIME 82 8277
TEST_TIME 83 8259
TEST_TIME 84 8313
TEST_TIME 85 8240
TEST_TIME 86 8279
TEST_TIME 87 8329
TEST_TIME 88 8325
TEST_TIME 89 8289
TEST_TIME 90 8322
TEST_TIME 91 8319
TEST_TIME 92 8452
TEST_TIME 93 8498
TEST_TIME 94 8523
TEST_TIME 95 8529
TEST_TIME 96 8561
TEST_TIME 97 8537
TEST_TIME 98 8590
TEST_TIME 99 8650
TEST_TIME 100 8625
TEST_TIME 101 8668
TEST_TIME 102 8608
TEST_TIME 103 8591
TEST_TIME 104 8560
TEST_TIME 105 8520
TEST_TIME 106 8540
TEST_TIME 107 8542
TEST_TIME 108 8534
TEST_TIME 109 8504
TEST_TIME 110 8473
TEST_TIME 111 8420
TEST_TIME 112 8480
TEST_TIME 113 8527
TEST_TIME 114 8558
TEST_TIME 115 8613
TEST_TIME 116 8644
TEST_TIME 117 8645
TEST_TIME 118 8621
TEST_TIME 119 8624
TEST_TIME 120 8663

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-08-2020 21:52:54 to 15-08-2020 21:52:57.

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 1426003 1426003 0 0.00 0 36939 9 0 0
doc_list_biud 1425998 1425998 0 0.00 0 32995 43 0 0
sp_add_invoice_to_stock 4593 4469 124 2.69 43 228461 25384 0 124
sp_cancel_adding_invoice 5478 5478 0 0.00 5 99667 1078 0 0
sp_cancel_client_order 1541 1541 0 0.00 5 11755 544 0 0
sp_cancel_customer_reserve 541649 541649 0 0.00 1 32998 89 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 3 3603 118 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 3 3487 108 0 0
sp_cancel_supplier_invoice 6084 6072 12 0.19 2 27946 1220 0 12
sp_cancel_supplier_order 394 394 0 0.00 7 1680889 160188 0 0
sp_cancel_write_off 9795 9795 0 0.00 1 15870 158 0 0
sp_client_order 18051 18051 0 0.00 3 27972 433 0 0
sp_customer_reserve 419579 419579 0 0.00 0 139564 244 0 0
sp_fill_shopping_cart 27682 27682 0 0.00 1 15772 51 0 0
sp_fill_shopping_cart_clo_res 419571 419571 0 0.00 0 17568 19 0 0
sp_get_clo_for_invoice 4460 4460 0 0.00 0 25605 411 0 0
sp_kill_cost_storno 16267 16267 0 0.00 0 19364 95 0 0
sp_kill_qstorno_ret_qs2qd 1099559 1099559 0 0.00 0 32991 48 0 0
sp_kill_qty_storno 578101 578101 0 0.00 0 32994 104 0 0
sp_lock_selected_doc 18263 18263 0 0.00 0 6646 5 0 0
sp_make_cost_storno 24474 24474 0 0.00 0 35932 94 0 0
sp_multiply_rows_for_pdistr 24473 24473 0 0.00 0 8892 42 0 0
sp_multiply_rows_for_qdistr 18051 18051 0 0.00 0 22411 184 0 0
sp_pay_from_customer 6177 6177 0 0.00 4 20278 178 0 0
sp_pay_to_supplier 5124 5124 0 0.00 4 36967 192 0 0
sp_payment_common 11297 11297 0 0.00 1 20269 43 0 0
sp_qd_handle_on_cancel_clo 1541 1541 0 0.00 0 11745 499 0 0
sp_qd_handle_on_invoice_upd_sts 9937 9937 0 0.00 0 20773 518 0 0
sp_qd_handle_on_reserve_upd_sts 8713 8713 0 0.00 0 21445 79 0 0
sp_reserve_write_off 8713 8713 0 0.00 6 21562 256 0 0
sp_supplier_invoice 4746 4746 0 0.00 7 94918 4536 0 0
sp_supplier_order 4899 4899 0 0.00 5 35244 1648 0 0
srv_aggregate_perf_data 94 94 0 0.00 2955 320804 23365 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1643 137815 43581 0 0
srv_find_qd_qs_mism 995912 995912 0 0.00 0 17600 7 0 0
srv_make_invnt_saldo 527 527 0 0.00 30 158102 1426 0 0
srv_make_money_saldo 724 724 0 0.00 1 18867 73 0 0
srv_recalc_idx_stat 4 4 0 0.00 183376 469092 302522 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4469 4469 0 0.00 0 24225 277 0 0
t$perf-norm:sp_cancel_adding_invoice 316 316 0 0.00 0 3910 113 0 0
t$perf-norm:sp_cancel_client_order 1541 1541 0 0.00 0 2153 20 0 0
t$perf-norm:sp_cancel_customer_reserve 575 575 0 0.00 0 1858 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 917 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 268 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 359 359 0 0.00 0 3764 43 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 52370 2454 0 0
t$perf-norm:sp_cancel_write_off 913 913 0 0.00 0 2686 15 0 0
t$perf-norm:sp_client_order 18051 18051 0 0.00 0 9119 30 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 1921 9 0 0
t$perf-norm:sp_pay_from_customer 6177 6177 0 0.00 0 2920 13 0 0
t$perf-norm:sp_pay_to_supplier 5124 5124 0 0.00 0 4172 13 0 0
t$perf-norm:sp_reserve_write_off 8713 8713 0 0.00 0 5400 15 0 0
t$perf-norm:sp_supplier_invoice 4746 4746 0 0.00 0 6828 28 0 0
t$perf-norm:sp_supplier_order 4899 4899 0 0.00 0 4397 30 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 1159 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 577 12 0 0
t$perf-norm:srv_make_invnt_saldo 527 527 0 0.00 0 1558 47 0 0
t$perf-norm:srv_make_money_saldo 724 724 0 0.00 0 2291 42 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 20 8 0 0
v_add_invoice_to_stock 9164 8916 248 2.70 0 5292 25 0 248
v_all_customers 32914 32914 0 0.00 0 73 1 0 0
v_all_suppliers 26915 26915 0 0.00 0 20 1 0 0
v_all_wares 36094 36094 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 632 632 0 0.00 0 1754 10 0 0
v_cancel_client_order 3082 3082 0 0.00 0 6256 13 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 3552 14 0 0
v_cancel_customer_reserve 1150 1150 0 0.00 0 7280 12 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 574 5 0 0
v_cancel_supplier_invoice 742 718 24 3.23 0 3073 28 0 24
v_cancel_supplier_order 788 788 0 0.00 0 6193 19 0 0
v_cancel_write_off 1826 1826 0 0.00 0 3439 17 0 0
v_max_id_clo_ord 4896 4896 0 0.00 0 566 2 0 0
v_max_id_clo_res 934 934 0 0.00 0 3534 16 0 0
v_max_id_ord_sup 4737 4737 0 0.00 0 4744 4 0 0
v_max_non_paid_invoice 1298 1298 0 0.00 0 4577 7 0 0
v_max_non_paid_realizn 3258 3258 0 0.00 0 4078 8 0 0
v_min_id_clo_ord 4896 4896 0 0.00 0 12020 17 0 0
v_min_id_clo_res 934 934 0 0.00 0 6577 17 0 0
v_min_id_ord_sup 4737 4737 0 0.00 0 4849 7 0 0
v_min_non_paid_invoice 5122 5122 0 0.00 0 19430 24 0 0
v_min_non_paid_realizn 6174 6174 0 0.00 0 19508 32 0 0
v_our_firm 9012 9012 0 0.00 0 21 1 0 0
v_reserve_write_off 17422 17422 0 0.00 0 6163 8 0 0
x_lock_depdocs_on_canc_invoice 11191 11191 0 0.00 0 21945 174 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 16896 615 0 0
x_make_qstorno_1000_1200 4899 4899 0 0.00 2 35232 1534 0 0
x_make_qstorno_1200_2000 4746 4746 0 0.00 3 90059 4349 0 0
x_make_qstorno_2100_3300 419579 419579 0 0.00 0 139563 221 0 0

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

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
19:22:00 1 8192000000 8798382528 8808857600 5057520 0.061 1 1 2 0 5175568 55488 62560
19:23:01 29037 8192000000 10368610480 10685906944 1422253552 14.793 100 94 187 2 1458807792 6377552 30176688
19:24:33 30340 8192000000 10424136720 10753613824 1475088320 15.258 100 85 170 1 1510661680 8451136 27122224
19:26:07 21928 8192000000 10472628912 10813632512 1523950224 15.685 100 84 167 1 1560228864 9982960 26295680
19:27:33 16793 8192000000 10552387024 10902687744 1610297232 16.427 100 85 170 1 1647940624 12081936 25561456
19:28:53 12366 8192000000 10597820448 10962903040 1663779840 16.881 100 77 152 2 1697673776 11267472 22626464
19:30:07 7237 8192000000 10663674336 11038003200 1719018640 17.344 100 73 147 1 1751601312 11713648 20869024
19:31:20 4960 8192000000 10704076720 11080257536 1768867216 17.758 100 81 161 4 1804538192 12421840 23249136
19:32:27 2806 8192000000 10733320992 11114049536 1799953712 18.014 100 84 169 1 1836485072 12990560 23540800
19:33:39 1970 8192000000 10769001024 11150659584 1836980880 18.316 100 92 183 1 1877178544 14758384 25439280
19:34:42 3383 8192000000 10790353152 11178221568 1852392720 18.442 100 77 154 1 1885717504 12619824 20704960
19:35:48 4878 8192000000 10801143696 11190382592 1864375664 18.539 100 80 159 3 1898610608 12426544 21808400
19:36:54 4565 8192000000 10775147808 11188228096 1861785264 18.518 100 30 60 2 1882225664 12126064 8314336
19:38:00 12398 8192000000 10767867040 11197816832 1868068576 18.569 100 15 29 2 1878116720 5955104 4093040
19:39:13 3501 8192000000 10808676896 11207921664 1882469648 18.685 100 67 131 6 1907342928 5676608 19196672
19:40:17 21895 8192000000 10785098368 11214114816 1873829392 18.615 100 20 40 0 1885458896 5912832 5716672
19:41:40 6472 8192000000 10791448928 11218341888 1877714880 18.647 100 24 48 1 1889828080 5446320 6666880
19:42:50 11071 8192000000 10776035616 11203272704 1836500368 18.312 98 17 33 1 1847091296 5615312 4975616
19:44:02 66883 8192000000 10738490352 11167760384 1724091584 17.386 92 41 82 2 1745720752 9032288 12596880
19:46:09 6560 8192000000 10712109440 11123277824 1787219104 17.909 99 32 64 3 1802588064 6059648 9309312
19:47:18 10243 8192000000 10712209344 11123429376 1778615856 17.838 99 17 34 1 1788365584 5021584 4728144
19:48:30 13709 8192000000 10722584048 11136389120 1806809984 18.070 100 20 40 1 1817656720 4979152 5867584
19:49:45 21292 8192000000 10733213648 11150290944 1785065568 17.891 98 15 30 0 1794741040 5398400 4277072
19:51:09 10487 8192000000 10758672816 11175796736 1837370032 18.319 100 14 29 0 1846462432 5113568 3978832
19:52:29 32042 8192000000 10799969472 11209277440 1868806192 18.575 100 44 86 3 1887357568 5429040 13122336
19:54:16 34215 8192000000 10827638896 11249360896 1898763584 18.816 100 49 98 1 1925706144 11673344 15269216
19:55:53 100321 8192000000 10842107952 11267284992 1912407632 18.926 100 30 60 1 1932106128 11369856 8328640
19:59:28 20691 8192000000 10872452768 11295514624 1939661392 19.144 100 61 122 2 1972338528 15103216 17573920
20:00:54 9266 8192000000 10900668416 11317497856 1952084272 19.243 100 58 115 1 1991212224 22562784 16565168
20:02:07 22118 8192000000 10902692208 11325272064 1960875920 19.313 100 48 96 4 1994485056 19296448 14312688
20:03:40 8702 8192000000 10940341488 11358740480 1984976848 19.504 100 61 119 4 2024995552 22730720 17287984
20:05:03 10831 8192000000 10960394240 11377815552 2003461904 19.650 100 73 144 2 2055094752 29947328 21685520
20:06:20 5809 8192000000 10981781312 11403358208 2013967872 19.733 100 62 123 2 2070568992 37581584 19019536
20:07:36 12444 8192000000 10985297840 11416367104 2024904816 19.819 100 63 126 0 2078968416 33983648 20079952
20:08:59 6165 8192000000 11009086512 11417006080 2022771840 19.802 100 85 168 3 2087744304 38913232 26059232
20:10:20 10916 8192000000 11006662096 11437486080 2034351408 19.893 100 65 130 2 2099588144 44394240 20842496
20:11:38 3619 8192000000 11022908400 11450302464 2040866592 19.944 100 76 153 0 2112720896 47567104 24287200
20:12:45 2452 8192000000 11045885152 11460177920 2045733312 19.982 100 83 167 2 2126035696 53061760 27240624
20:13:57 5980 8192000000 11021583056 11443044352 2025331696 19.822 100 75 148 1 2106549680 57570208 23647776
20:15:13 3986 8192000000 11036102496 11446554624 2027280832 19.837 100 84 165 1 2112019600 58139328 26599440
20:16:26 2182 8192000000 11013988320 11429433344 2007546064 19.682 100 88 174 0 2101280880 65819872 27914944
20:17:40 3536 8192000000 11024769984 11438112768 2016148624 19.750 100 87 175 0 2109950576 66793392 27008560
20:18:49 1635 8192000000 11019648288 11435470848 2007074992 19.678 100 79 158 1 2097239056 65469424 24694640
20:19:59 3097 8192000000 10989824528 11406909440 1982104752 19.481 100 82 162 1 2076168864 67639456 26424656
20:21:10 5084 8192000000 10972325584 11380760576 1947225456 19.204 98 76 151 1 2037169200 65428912 24514832
20:22:27 4072 8192000000 10989601968 11399737344 1975443744 19.429 100 91 177 1 2069319072 65414560 28460768
20:23:41 12717 8192000000 10952974832 11384856576 1964769024 19.344 100 57 114 0 2045155280 61696416 18689840
20:25:17 21387 8192000000 10930487792 11367993344 1927807776 19.049 99 32 62 0 1997116032 58428864 10879392
20:26:47 18426 8192000000 10922273136 11369267200 1936207328 19.116 100 24 48 0 2001952848 56892736 8852784
20:28:14 38001 8192000000 10887993984 11357483008 1933545584 19.095 99 25 50 1 1988643696 46404176 8693936
20:29:57 76978 8192000000 10875940016 11321085952 1924980512 19.027 99 37 73 1 1949464816 14381184 10103120
20:32:18 92777 8192000000 10868268800 11317706752 1907535984 18.887 98 38 76 1 1930149168 11500240 11112944
20:34:52 46788 8192000000 10871685392 11334172672 1949592128 19.223 100 10 20 1 1963824240 11193552 3038560
20:36:40 28325 8192000000 10901634208 11355590656 1971092992 19.394 100 25 48 1 1989950160 11733728 7123440
20:38:10 38474 8192000000 10869941696 11331129344 1928601600 19.056 99 20 40 0 1954471472 19442400 6427472
20:39:51 65837 8192000000 10853102304 11315486720 1889453712 18.741 97 12 25 1 1904926768 12491536 2981520
20:42:10 136314 8192000000 10842219792 11302060032 1890761264 18.752 98 16 32 1 1905487760 9907280 4819216
20:46:01 19309 8192000000 10862776112 11324493824 1940952672 19.154 100 24 46 1 1957631968 10036160 6643136
20:48:00 25809 8192000000 10874561504 11359641600 1947254720 19.205 100 9 18 1 1976610592 26362816 2993056
20:49:27 68642 8192000000 10875451808 11345694720 1932435536 19.086 99 40 80 3 1969649664 25501712 11712416
20:52:00 60115 8192000000 10873205616 11344797696 1948764960 19.217 100 19 38 1 1975504416 21331936 5407520
20:54:08 116882 8192000000 10854444320 11316703232 1887689792 18.727 97 12 22 1 1909380928 18269072 3422064
20:57:12 55054 8192000000 10893411872 11360755712 1964372288 19.341 100 12 24 1 1985460880 17572032 3516560
20:59:08 22066 8192000000 10869085024 11313864704 1902444048 18.846 98 32 64 3 1921193936 9708192 9041696
21:00:34 92504 8192000000 10880139680 11354628096 1911438800 18.918 98 26 52 1 1943571936 24624784 7508352
21:03:06 54593 8192000000 10895051104 11352641536 1944743472 19.185 99 38 77 2 1966961472 11533664 10684336
21:05:05 137815 8192000000 10873704160 11340759040 1918739648 18.977 98 34 68 1 1943032736 13959520 10333568
21:08:25 31745 8192000000 10877860384 11328815104 1898058896 18.811 97 34 68 1 1919734112 11934800 9740416
21:10:01 64603 8192000000 10872374048 11349024768 1955807104 19.273 100 30 60 3 1977087648 12839024 8441520
21:12:06 44233 8192000000 10848422544 11323084800 1896590880 18.799 98 13 27 2 1914100816 13133504 4376432
21:13:53 39607 8192000000 10880344288 11338248192 1943691024 19.176 100 15 30 0 1962132368 13872832 4568512
21:15:33 76138 8192000000 10850984784 11303297024 1913641904 18.936 99 20 40 1 1934607360 15377232 5588224
21:17:51 80725 8192000000 10841515696 11317886976 1870308576 18.587 97 10 20 0 1886774256 13839392 2626288
21:20:12 50007 8192000000 10861565760 11300552704 1868447104 18.572 97 31 62 2 1892033168 14005328 9580736
21:22:03 60272 8192000000 10874521776 11329757184 1903550848 18.855 98 12 24 1 1922141088 15272640 3317600
21:24:37 110592 8192000000 10898929808 11342716928 1908786928 18.897 98 15 30 1 1925609360 12868112 3954320
21:27:34 52258 8192000000 10865874256 11312340992 1902649136 18.848 98 30 60 0 1921021520 10363792 8008592
21:29:27 40961 8192000000 10885879904 11331026944 1887271168 18.724 97 29 58 1 1907433408 12192448 7969792
21:31:23 32872 8192000000 10881344112 11315154944 1918620592 18.976 99 34 68 1 1939300160 11465120 9214448
21:32:59 94475 8192000000 10896368992 11345281024 1959846224 19.305 100 33 64 0 1981150272 11479872 9824176
21:35:35 64862 8192000000 10888805232 11350118400 1947924224 19.210 99 36 73 3 1970828288 13354976 9549088
21:37:51 54652 8192000000 10869641872 11341467648 1905293680 18.869 97 11 22 0 1923189200 14931808 2963712
21:39:46 42953 8192000000 10900866240 11357773824 1969717104 19.383 100 27 54 3 1996276880 17636224 8923552
21:41:29 80492 8192000000 10899248960 11365838848 1943246928 19.173 98 17 34 1 1964671280 16264784 5159568
21:45:04 33670 8192000000 10871151984 11345289216 1930416672 19.070 99 14 28 2 1951254992 16637168 4201152
21:46:38 51714 8192000000 10852525264 11320938496 1857815696 18.486 95 15 30 2 1874600848 12540080 4245072
21:48:38 78106 8192000000 10880237184 11319693312 1825652720 18.224 94 26 52 1 1842649616 9607296 7389600
21:50:57 66197 8192000000 9829265056 10181529600 951354064 10.404 50 9 18 0 965970336 12081296 2534976

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 21:53:03 to 15-08-2020 21:53:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 124 v_add_invoice_to_stock 335544517
user_exc 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-08-2020 21:53:18 to 15-08-2020 21:53:20.

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          216128
MON$OLDEST_ACTIVE               216129
MON$OLDEST_SNAPSHOT             216129
MON$NEXT_TRANSACTION            216129
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                       2020800
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.33355 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33355 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33355 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33355 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Sat Aug 15 21:52:09 2020
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Sat Aug 15 21:52:09 2020
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Sat Aug 15 21:52:09 2020
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Sat Aug 15 21:52:09 2020
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:55: 
--- end of diff output ---

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

15.08.20 21:53:22. 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: 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 21:53:24 NO_SEVERE_ERRORS_FOUND
15.08.20 21:53:26. End of report.