Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.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 17021576 kB
MemAvailable 30899912 kB
Buffers 0 kB
Cached 14623432 kB
SwapCached 6012 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-21 21:51:57.2520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/53264
TRN_ID                          218654
ATT_ID                          578
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: 8817.55 21170 1058106
customer order: creation 168.15 399 20178
customer order: refuse 13.98 497 1677
order to supplier: creation 44.48 1488 5338
order to supplier: removal 3.29 124700 395
invoice (draft): creation 44.11 4032 5293
invoice (draft): removal 49.53 1009 5943
invoice accept: apply 40.49 24022 4859
invoice accept: cancel 44.91 1181 5389
customer reserve: creation 3768.92 233 452270
customer reserve: removal 4342.36 73 521083
realization accept: apply 81.74 228 9809
realization accept: cancel 89.43 145 10731
payment from customer: creation 57.11 148 6853
payment from customer: removal 5.57 130 668
payment to supplier: creation 47.97 157 5756
payment to supplier: removal 3.45 88 414
service: total inventory turnovers 4.42 1403 530
service: total monetary turnovers 6.68 57 801
service: aggregate perf. data 0.96 17684 115
service: refresh index statistics 0.03 245718 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2913
TEST_TIME 2 2822
TEST_TIME 3 4062
TEST_TIME 4 4751
TEST_TIME 5 4653
TEST_TIME 6 4300
TEST_TIME 7 4501
TEST_TIME 8 4522
TEST_TIME 9 4506
TEST_TIME 10 4480
TEST_TIME 11 4721
TEST_TIME 12 4884
TEST_TIME 13 4965
TEST_TIME 14 4881
TEST_TIME 15 4934
TEST_TIME 16 5099
TEST_TIME 17 5665
TEST_TIME 18 5666
TEST_TIME 19 5632
TEST_TIME 20 5734
TEST_TIME 21 5840
TEST_TIME 22 6084
TEST_TIME 23 6100
TEST_TIME 24 6091
TEST_TIME 25 6414
TEST_TIME 26 6897
TEST_TIME 27 7199
TEST_TIME 28 7674
TEST_TIME 29 7714
TEST_TIME 30 7669
TEST_TIME 31 8148
TEST_TIME 32 8258
TEST_TIME 33 8121
TEST_TIME 34 8403
TEST_TIME 35 8431
TEST_TIME 36 8649
TEST_TIME 37 8991
TEST_TIME 38 8878
TEST_TIME 39 8804
TEST_TIME 40 8789
TEST_TIME 41 8676
TEST_TIME 42 8611
TEST_TIME 43 8664
TEST_TIME 44 8651
TEST_TIME 45 8499
TEST_TIME 46 8558
TEST_TIME 47 8498
TEST_TIME 48 8480
TEST_TIME 49 8392
TEST_TIME 50 8369
TEST_TIME 51 8346
TEST_TIME 52 8406
TEST_TIME 53 8413
TEST_TIME 54 8398
TEST_TIME 55 8391
TEST_TIME 56 8567
TEST_TIME 57 8554
TEST_TIME 58 8586
TEST_TIME 59 8659
TEST_TIME 60 8647
TEST_TIME 61 8683
TEST_TIME 62 8803
TEST_TIME 63 8773
TEST_TIME 64 8713
TEST_TIME 65 8710
TEST_TIME 66 8841
TEST_TIME 67 8829
TEST_TIME 68 8877
TEST_TIME 69 8986
TEST_TIME 70 8968
TEST_TIME 71 8917
TEST_TIME 72 8998
TEST_TIME 73 8943
TEST_TIME 74 8882
TEST_TIME 75 8864
TEST_TIME 76 8776
TEST_TIME 77 8726
TEST_TIME 78 8739
TEST_TIME 79 8770
TEST_TIME 80 8765
TEST_TIME 81 8709
TEST_TIME 82 8654
TEST_TIME 83 8630
TEST_TIME 84 8564
TEST_TIME 85 8578
TEST_TIME 86 8646
TEST_TIME 87 8635
TEST_TIME 88 8622
TEST_TIME 89 8609
TEST_TIME 90 8730
TEST_TIME 91 8762
TEST_TIME 92 8762
TEST_TIME 93 8751
TEST_TIME 94 8764
TEST_TIME 95 8758
TEST_TIME 96 8810
TEST_TIME 97 8807
TEST_TIME 98 8828
TEST_TIME 99 8843
TEST_TIME 100 8776
TEST_TIME 101 8750
TEST_TIME 102 8750
TEST_TIME 103 8704
TEST_TIME 104 8751
TEST_TIME 105 8708
TEST_TIME 106 8671
TEST_TIME 107 8633
TEST_TIME 108 8684
TEST_TIME 109 8647
TEST_TIME 110 8620
TEST_TIME 111 8674
TEST_TIME 112 8672
TEST_TIME 113 8667
TEST_TIME 114 8658
TEST_TIME 115 8721
TEST_TIME 116 8690
TEST_TIME 117 8781
TEST_TIME 118 8797
TEST_TIME 119 8783
TEST_TIME 120 8822
TEST_TIME 121 8749

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-08-2020 21:52:56 to 21-08-2020 21:52:59.

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 1472934 1472934 0 0.00 0 16445 9 0 0
doc_list_biud 1472934 1472934 0 0.00 0 51445 34 0 0
sp_add_invoice_to_stock 4916 4859 57 1.15 30 230464 24022 0 57
sp_cancel_adding_invoice 5389 5389 0 0.00 5 83271 1181 0 0
sp_cancel_client_order 1677 1677 0 0.00 4 17922 497 0 0
sp_cancel_customer_reserve 521083 521083 0 0.00 1 39508 74 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 5285 131 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 2300 89 0 0
sp_cancel_supplier_invoice 5950 5943 7 0.11 1 51458 1009 0 7
sp_cancel_supplier_order 395 395 0 0.00 9 1398742 124701 0 0
sp_cancel_write_off 10731 10731 0 0.00 1 13852 145 0 0
sp_client_order 20178 20178 0 0.00 4 24906 400 0 0
sp_customer_reserve 452270 452270 0 0.00 0 108599 234 0 0
sp_fill_shopping_cart 30800 30800 0 0.00 1 16824 44 0 0
sp_fill_shopping_cart_clo_res 452245 452245 0 0.00 0 18364 15 0 0
sp_get_clo_for_invoice 4833 4833 0 0.00 0 17811 314 0 0
sp_kill_cost_storno 17202 17202 0 0.00 0 13849 83 0 0
sp_kill_qstorno_ret_qs2qd 1059235 1059235 0 0.00 0 51440 40 0 0
sp_kill_qty_storno 559860 559860 0 0.00 0 51443 87 0 0
sp_lock_selected_doc 20226 20226 0 0.00 0 10133 6 0 0
sp_make_cost_storno 27251 27251 0 0.00 0 16439 69 0 0
sp_multiply_rows_for_pdistr 27251 27251 0 0.00 0 13273 39 0 0
sp_multiply_rows_for_qdistr 20178 20178 0 0.00 0 21549 177 0 0
sp_pay_from_customer 6853 6853 0 0.00 4 16793 148 0 0
sp_pay_to_supplier 5756 5756 0 0.00 5 15359 158 0 0
sp_payment_common 12609 12609 0 0.00 2 13798 37 0 0
sp_qd_handle_on_cancel_clo 1677 1677 0 0.00 0 17914 455 0 0
sp_qd_handle_on_invoice_upd_sts 10222 10222 0 0.00 0 16403 442 0 0
sp_qd_handle_on_reserve_upd_sts 9809 9809 0 0.00 0 16969 75 0 0
sp_reserve_write_off 9809 9809 0 0.00 6 16982 228 0 0
sp_supplier_invoice 5293 5293 0 0.00 6 82208 4033 0 0
sp_supplier_order 5338 5338 0 0.00 5 51030 1488 0 0
srv_aggregate_perf_data 115 115 0 0.00 2249 227384 17684 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 712 143317 40947 0 0
srv_find_qd_qs_mism 1009560 1009560 0 0.00 0 14018 7 0 0
srv_make_invnt_saldo 530 530 0 0.00 54 98434 1404 0 0
srv_make_money_saldo 801 801 0 0.00 1 12171 57 0 0
srv_recalc_idx_stat 4 4 0 0.00 179805 395433 245718 0 0
t$perf-abend:sp_add_invoice_to_stock 57 57 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 4859 4859 0 0.00 0 10840 263 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 4445 149 0 0
t$perf-norm:sp_cancel_client_order 1677 1677 0 0.00 0 2309 17 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 4302 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 5566 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 1312 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 724 20 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 36501 2235 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 4064 13 0 0
t$perf-norm:sp_client_order 20178 20178 0 0.00 0 13124 25 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 717 6 0 0
t$perf-norm:sp_pay_from_customer 6853 6853 0 0.00 0 8242 15 0 0
t$perf-norm:sp_pay_to_supplier 5756 5756 0 0.00 0 7296 13 0 0
t$perf-norm:sp_reserve_write_off 9809 9809 0 0.00 0 5770 14 0 0
t$perf-norm:sp_supplier_invoice 5293 5293 0 0.00 0 7110 30 0 0
t$perf-norm:sp_supplier_order 5338 5338 0 0.00 0 5090 27 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 487 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1284 26 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 2243 48 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 1347 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 31 11 0 0
v_add_invoice_to_stock 9780 9666 114 1.16 0 2823 17 0 114
v_all_customers 36956 36956 0 0.00 0 21 1 0 0
v_all_suppliers 29608 29608 0 0.00 0 19 1 0 0
v_all_wares 40356 40356 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 9081 19 0 0
v_cancel_client_order 3354 3354 0 0.00 0 7328 8 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 2349 8 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 2096 5 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 2073 6 0 0
v_cancel_supplier_invoice 762 748 14 1.83 0 2553 23 0 14
v_cancel_supplier_order 790 790 0 0.00 0 515 4 0 0
v_cancel_write_off 1952 1952 0 0.00 0 488 14 0 0
v_max_id_clo_ord 5336 5336 0 0.00 0 2457 2 0 0
v_max_id_clo_res 963 963 0 0.00 0 2629 12 0 0
v_max_id_ord_sup 5286 5286 0 0.00 0 5926 3 0 0
v_max_non_paid_invoice 1574 1574 0 0.00 0 1089 4 0 0
v_max_non_paid_realizn 3543 3543 0 0.00 0 937 4 0 0
v_min_id_clo_ord 5336 5336 0 0.00 0 3405 9 0 0
v_min_id_clo_res 963 963 0 0.00 0 4644 19 0 0
v_min_id_ord_sup 5286 5286 0 0.00 0 5171 6 0 0
v_min_non_paid_invoice 5756 5756 0 0.00 0 7941 22 0 0
v_min_non_paid_realizn 6853 6853 0 0.00 0 6867 19 0 0
v_our_firm 10020 10020 0 0.00 0 25 1 0 0
v_reserve_write_off 19618 19618 0 0.00 0 7930 6 0 0
x_lock_depdocs_on_canc_invoice 10958 10958 0 0.00 0 23754 156 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 11722 466 0 0
x_make_qstorno_1000_1200 5338 5338 0 0.00 2 50983 1372 0 0
x_make_qstorno_1200_2000 5293 5293 0 0.00 3 82190 3880 0 0
x_make_qstorno_2100_3300 452270 452270 0 0.00 0 108597 214 0 0

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

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:02 32017 8192000000 10344419840 10657583104 1399230240 14.588 100 91 182 0 1435675696 5902176 30543280
19:24:55 26240 8192000000 10420265904 10746740736 1466111200 15.180 100 87 174 0 1501856320 7608432 28136688
19:26:24 25619 8192000000 10467034352 10806853632 1526497952 15.707 100 79 158 0 1559906832 9021984 24386896
19:27:57 14486 8192000000 10551893840 10907230208 1610387440 16.428 100 72 143 2 1641776128 9997680 21391008
19:29:16 13918 8192000000 10631213216 10993504256 1687831472 17.083 100 80 160 0 1723791424 12220304 23739648
19:30:36 6857 8192000000 10674236592 11049369600 1739276368 17.513 100 69 138 1 1771480080 11567952 20635760
19:31:45 5120 8192000000 10726362704 11100987392 1784771440 17.889 100 91 180 0 1823823056 13789168 25262448
19:32:55 4795 8192000000 10748967984 11129610240 1814025520 18.129 100 82 164 0 1850387888 13266656 23095712
19:34:01 2793 8192000000 10777053120 11161141248 1840198256 18.342 100 88 176 1 1876464064 11304576 24961232
19:35:07 4923 8192000000 10793776640 11175034880 1851405968 18.434 100 86 172 0 1888706352 13049904 24250480
19:36:17 1736 8192000000 10802802704 11185291264 1858038464 18.487 100 91 182 1 1897805616 13925984 25841168
19:37:22 30884 8192000000 10799798368 11193495552 1869729024 18.582 100 53 106 1 1893875472 9156416 14990032
19:38:56 3861 8192000000 10799574496 11216809984 1885861824 18.712 100 58 115 2 1911230880 8924176 16444880
19:40:02 2851 8192000000 10808007232 11220865024 1885493312 18.709 100 51 100 4 1907526848 6366768 15666768
19:41:09 5558 8192000000 10809924032 11227234304 1891196848 18.755 100 29 58 1 1905342000 5731168 8413984
19:42:20 3840 8192000000 10821638880 11239866368 1896915360 18.801 100 42 84 0 1918147696 9614928 11617408
19:43:27 18927 8192000000 10822113696 11234320384 1883481792 18.693 99 60 119 2 1906880592 6216208 17182592
19:44:47 7520 8192000000 10794317056 11213811712 1875711248 18.630 100 30 58 2 1890487584 5650288 9126048
19:45:57 5756 8192000000 10752460384 11177189376 1823936816 18.210 98 32 64 3 1839064336 6176352 8951168
19:47:07 20763 8192000000 10725001008 11125456896 1699051664 17.177 92 37 75 2 1716933216 6994784 10886768
19:48:31 4349 8192000000 10699353328 11094302720 1737974928 17.502 97 44 88 0 1756275152 5169184 13131040
19:49:38 8471 8192000000 10689967168 11100778496 1751346976 17.613 98 19 38 2 1762769280 5437568 5984736
19:50:48 51789 8192000000 10707515984 11127947264 1764243280 17.719 97 16 32 1 1774241744 5223248 4775216
19:52:46 36373 8192000000 10787593152 11190272000 1840087360 18.342 99 67 134 0 1869317328 10947232 18282736
19:54:24 22764 8192000000 10786927728 11205533696 1858194416 18.489 99 42 83 1 1879497456 9487408 11815632
19:55:53 3343 8192000000 10822661744 11229773824 1891412816 18.757 100 51 101 0 1914846112 8986960 14446336
19:57:10 40564 8192000000 10839438368 11264262144 1913231616 18.933 100 37 73 2 1936549072 12687408 10630048
19:58:57 40013 8192000000 10865501600 11291295744 1929035296 19.059 100 39 76 0 1957022768 15640288 12347184
20:00:44 23562 8192000000 10879706528 11311104000 1944022800 19.179 100 38 74 2 1974931824 19731904 11177120
20:02:12 22973 8192000000 10907460848 11331719168 1961533728 19.318 100 51 102 1 1999734752 22561952 15639072
20:03:41 5645 8192000000 10919673920 11341996032 1974195872 19.419 100 66 131 1 2014767264 21691808 18879584
20:04:52 7818 8192000000 10943692160 11366371328 1992393216 19.563 100 60 120 0 2034460112 23751040 18315856
20:06:05 14609 8192000000 10958515504 11381817344 1997184512 19.601 100 62 123 0 2047363424 31097568 19081344
20:07:30 10133 8192000000 10968831712 11390799872 2002014144 19.639 100 68 137 1 2055606832 32623360 20969328
20:08:42 11939 8192000000 10958893840 11393851392 2003278128 19.649 100 43 86 1 2045596864 29052496 13266240
20:10:00 3904 8192000000 10979092352 11402813440 2016837184 19.755 100 71 142 2 2069363200 31779488 20746528
20:11:14 1184 8192000000 11007435504 11424141312 2027900000 19.842 100 83 166 2 2087867136 35244288 24722848
20:12:22 1497 8192000000 11009506656 11424653312 2026147824 19.828 100 92 181 1 2092923296 38763216 28012256
20:13:30 1345 8192000000 10971633856 11377041408 1966472288 19.357 99 81 160 5 2029511600 38580960 24458352
20:14:44 9063 8192000000 10976616304 11403157504 1996543264 19.595 100 67 134 3 2062425408 45435328 20446816
20:15:59 1746 8192000000 11006083280 11419017216 2009374704 19.697 100 95 188 1 2095274704 57727696 28172304
20:17:05 710 8192000000 11015217920 11426217984 2009331824 19.696 100 94 190 1 2094537088 55913968 29291296
20:18:13 1555 8192000000 11005220064 11415347200 2007562384 19.682 100 93 184 0 2082833568 46718240 28552944
20:19:25 2949 8192000000 10948614784 11373785088 1970763264 19.392 100 64 128 0 2031840480 41199504 19877712
20:20:36 7600 8192000000 10913078992 11350077440 1936760096 19.121 99 46 92 0 1985638368 34052288 14825984
20:21:52 1778 8192000000 10919080192 11326967808 1933041712 19.091 99 82 161 1 1993517424 36902224 23573488
20:23:00 10616 8192000000 10912947264 11363262464 1957690368 19.288 100 29 58 0 1996602128 29050560 9861200
20:24:16 28671 8192000000 10879824688 11325599744 1908048992 18.891 98 24 46 2 1941866656 26323328 7494336
20:25:58 33057 8192000000 10872180496 11326529536 1884162048 18.699 97 22 44 1 1920873552 29520000 7191504
20:27:46 51347 8192000000 10858230784 11299176448 1888963008 18.737 98 22 45 1 1919205552 22804000 7438544
20:29:46 45508 8192000000 10878212432 11298443264 1904292624 18.861 98 43 85 1 1925003200 9219056 11491520
20:31:35 65602 8192000000 10885080256 11323076608 1941046224 19.155 100 31 62 2 1956515600 6478800 8990576
20:33:43 72798 8192000000 10888636128 11318571008 1935725040 19.113 100 30 61 2 1956847920 12557648 8565232
20:36:01 63220 8192000000 10865439776 11320688640 1940836992 19.153 100 34 66 1 1961205936 10382960 9985984
20:38:06 15907 8192000000 10886640688 11329884160 1952116672 19.243 100 35 67 2 1973365440 11800096 9448672
20:39:31 1549 8192000000 10934271264 11360751616 1975762592 19.431 100 68 135 1 2014167184 17829504 20575088
20:40:36 21180 8192000000 10939895840 11364827136 1970785664 19.392 100 50 97 2 2010412896 23918832 15708400
20:42:01 16005 8192000000 10910400800 11361939456 1963935584 19.337 100 32 64 1 2004920272 30152048 10832640
20:43:25 77874 8192000000 10900973888 11368374272 1945981360 19.194 99 25 50 1 1984258448 29913744 8363344
20:45:46 47365 8192000000 10905209872 11343286272 1933491456 19.095 99 18 36 1 1966318320 26274928 6551936
20:47:35 53556 8192000000 10891512672 11357552640 1953032480 19.251 100 25 50 3 1988790688 27583696 8174512
20:49:32 58177 8192000000 10884997568 11352858624 1952390144 19.246 100 15 30 0 1970662496 13949408 4322944
20:51:37 94592 8192000000 10884922560 11364302848 1945511936 19.191 99 10 20 0 1963662080 15620064 2530080
20:54:12 37575 8192000000 10890251792 11346173952 1956493872 19.278 100 17 34 1 1970928800 10190560 4244368
20:55:54 18356 8192000000 10920927424 11360157696 1973269584 19.411 100 46 91 2 2002928944 16378016 13281344
20:57:15 71636 8192000000 10897683760 11360538624 1928667232 19.056 98 12 24 0 1943199264 11537904 2994128
20:59:44 63159 8192000000 10866973200 11339812864 1884708304 18.703 96 12 24 1 1915774320 27291792 3774224
21:02:00 37748 8192000000 10900633648 11323686912 1920716080 18.993 99 40 76 1 1942761824 10503536 11542208
21:03:38 42570 8192000000 10880419280 11329282048 1905173488 18.868 97 28 56 2 1925782496 12793488 7815520
21:05:24 114795 8192000000 10861109808 11316097024 1875915040 18.632 96 22 45 0 1889067376 6604704 6547632
21:08:22 17508 8192000000 10870631664 11331731456 1947949120 19.210 100 25 50 1 1963158400 7984912 7224368
21:09:47 55207 8192000000 10859790608 11325046784 1922797376 19.009 98 24 48 3 1940970624 11159840 7013408
21:12:28 82750 8192000000 10863320224 11326296064 1827656896 18.240 93 15 29 1 1844921264 12636256 4628112
21:15:09 26487 8192000000 10878200592 11353423872 1951739488 19.240 100 8 16 1 1974779184 20695568 2344128
21:16:36 45827 8192000000 10876616256 11339419648 1947396752 19.206 100 16 32 2 1967444064 15579968 4467344
21:18:46 83184 8192000000 10867577936 11329445888 1859789264 18.502 95 17 34 0 1884747072 19864304 5093504
21:21:10 95232 8192000000 10820339776 11296710656 1777779936 17.831 92 8 16 0 1799818368 19947440 2090992
21:23:50 35119 8192000000 10851364016 11293765632 1886346976 18.716 98 26 53 2 1901467840 8406880 6713984
21:25:28 16826 8192000000 10883729856 11331411968 1942367888 19.166 100 37 74 1 1963454960 11122112 9964960
21:26:53 44455 8192000000 10883515552 11330371584 1909794320 18.905 98 35 70 2 1936062096 16816112 9451664
21:28:39 143317 8192000000 10870598288 11325530112 1811399168 18.107 93 29 58 1 1835300704 16040128 7861408
21:32:12 109143 8192000000 10870556832 11334266880 1926715024 19.041 99 15 28 0 1948472704 17568336 4189344
21:35:10 28739 8192000000 10885313520 11344154624 1954220384 19.260 100 22 42 2 1979131168 19028544 5882240
21:36:39 39749 8192000000 10868537488 11337150464 1909502176 18.903 98 12 24 3 1932718096 19798880 3417040
21:38:19 90729 8192000000 10879996336 11362013184 1938316512 19.133 99 17 33 3 1961622640 18045040 5261088
21:41:03 52355 8192000000 10879942256 11346235392 1933598048 19.096 98 31 62 1 1953391552 11022288 8771216
21:43:06 87629 8192000000 10863902112 11327676416 1901236256 18.836 97 16 32 1 1922500896 16745232 4519408
21:45:41 71996 8192000000 10886968320 11362791424 1957987312 19.290 100 23 46 1 1983127920 18330384 6810224
21:47:57 130760 8192000000 10879427120 11345670144 1896886048 18.801 97 10 20 0 1920104096 20698176 2519872
21:51:11 70412 8192000000 9360729184 9634455552 504892832 5.805 26 2 4 0 513182496 7942848 346816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-08-2020 21:53:05 to 21-08-2020 21:53:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 57 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:02, from 21-08-2020 21:53:20 to 21-08-2020 21:53:22.

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          218918
MON$OLDEST_ACTIVE               218919
MON$OLDEST_SNAPSHOT             218919
MON$NEXT_TRANSACTION            218919
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                       2011072
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.33357 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33357 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33357 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33357 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 ---
--- end of diff output ---

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

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