Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.10.2020 18: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 e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
./oltp_isql_run_worker.sh line 45: dmidecode: command not found
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.00
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 32643792 kB
MemFree 17396460 kB
MemAvailable 31594356 kB
Buffers 0 kB
Cached 14249788 kB
SwapCached 5760 kB
Command: fdisk -l
./oltp_isql_run_worker.sh: line 45: fdisk: command not found

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-10-06 18:52:34
DTS_END                         2020-10-06 18:52:09.0190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/39114
TRN_ID                          246055
ATT_ID                          704
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: 9661.17 21684 1159341
customer order: creation 173.67 395 20840
customer order: refuse 15.34 261 1841
order to supplier: creation 45.92 1116 5510
order to supplier: removal 3.38 92316 406
invoice (draft): creation 46.67 2919 5600
invoice (draft): removal 56.74 632 6809
invoice accept: apply 42.21 15447 5065
invoice accept: cancel 50.90 621 6108
customer reserve: creation 3897.54 153 467705
customer reserve: removal 5022.55 49 602706
realization accept: apply 86.08 152 10330
realization accept: cancel 89.16 97 10699
payment from customer: creation 60.85 87 7302
payment from customer: removal 5.67 54 680
payment to supplier: creation 49.54 83 5945
payment to supplier: removal 3.57 64 428
service: total inventory turnovers 4.67 1035 560
service: total monetary turnovers 5.88 38 705
service: aggregate perf. data 0.82 21646 98
service: refresh index statistics 0.03 296521 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-10-2020 18:53:15 to 06-10-2020 18:53:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4166
TEST_TIME 2 6068
TEST_TIME 3 5358
TEST_TIME 4 5189
TEST_TIME 5 4978
TEST_TIME 6 5264
TEST_TIME 7 5217
TEST_TIME 8 5608
TEST_TIME 9 6258
TEST_TIME 10 6356
TEST_TIME 11 6718
TEST_TIME 12 6884
TEST_TIME 13 7635
TEST_TIME 14 8317
TEST_TIME 15 8941
TEST_TIME 16 9561
TEST_TIME 17 10139
TEST_TIME 18 10784
TEST_TIME 19 10501
TEST_TIME 20 11322
TEST_TIME 21 12555
TEST_TIME 22 12026
TEST_TIME 23 11849
TEST_TIME 24 11644
TEST_TIME 25 11342
TEST_TIME 26 11265
TEST_TIME 27 11123
TEST_TIME 28 10845
TEST_TIME 29 10743
TEST_TIME 30 10643
TEST_TIME 31 10721
TEST_TIME 32 10704
TEST_TIME 33 10679
TEST_TIME 34 10790
TEST_TIME 35 10742
TEST_TIME 36 10685
TEST_TIME 37 10808
TEST_TIME 38 10636
TEST_TIME 39 10773
TEST_TIME 40 10729
TEST_TIME 41 10620
TEST_TIME 42 10765
TEST_TIME 43 10744
TEST_TIME 44 10699
TEST_TIME 45 10729
TEST_TIME 46 10877
TEST_TIME 47 10751
TEST_TIME 48 10540
TEST_TIME 49 10548
TEST_TIME 50 10455
TEST_TIME 51 10401
TEST_TIME 52 10302
TEST_TIME 53 10256
TEST_TIME 54 10293
TEST_TIME 55 10162
TEST_TIME 56 10224
TEST_TIME 57 10202
TEST_TIME 58 10264
TEST_TIME 59 10213
TEST_TIME 60 10256
TEST_TIME 61 10308
TEST_TIME 62 10349
TEST_TIME 63 10366
TEST_TIME 64 10419
TEST_TIME 65 10457
TEST_TIME 66 10529
TEST_TIME 67 10454
TEST_TIME 68 10545
TEST_TIME 69 10422
TEST_TIME 70 10339
TEST_TIME 71 10252
TEST_TIME 72 10263
TEST_TIME 73 10224
TEST_TIME 74 10153
TEST_TIME 75 10099
TEST_TIME 76 10043
TEST_TIME 77 10043
TEST_TIME 78 10046
TEST_TIME 79 10056
TEST_TIME 80 10015
TEST_TIME 81 9971
TEST_TIME 82 10060
TEST_TIME 83 10146
TEST_TIME 84 10129
TEST_TIME 85 10114
TEST_TIME 86 10203
TEST_TIME 87 10175
TEST_TIME 88 10148
TEST_TIME 89 10133
TEST_TIME 90 10044
TEST_TIME 91 10030
TEST_TIME 92 9963
TEST_TIME 93 9945
TEST_TIME 94 9919
TEST_TIME 95 9895
TEST_TIME 96 9850
TEST_TIME 97 9808
TEST_TIME 98 9846
TEST_TIME 99 9819
TEST_TIME 100 9843
TEST_TIME 101 9833
TEST_TIME 102 9803
TEST_TIME 103 9769
TEST_TIME 104 9939
TEST_TIME 105 9941
TEST_TIME 106 9955
TEST_TIME 107 9950
TEST_TIME 108 10028
TEST_TIME 109 9981
TEST_TIME 110 9895
TEST_TIME 111 9846
TEST_TIME 112 9823
TEST_TIME 113 9777
TEST_TIME 114 9770
TEST_TIME 115 9726
TEST_TIME 116 9693
TEST_TIME 117 9750
TEST_TIME 118 9725
TEST_TIME 119 9715
TEST_TIME 120 9662

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-10-2020 18:53:19 to 06-10-2020 18:53:22.

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 1592532 1592532 0 0.00 0 17854 5 0 0
doc_list_biud 1592532 1592532 0 0.00 0 22604 23 0 0
sp_add_invoice_to_stock 5164 5065 99 1.91 27 178675 15447 0 99
sp_cancel_adding_invoice 6108 6108 0 0.00 5 65389 621 0 0
sp_cancel_client_order 1841 1841 0 0.00 4 8103 262 0 0
sp_cancel_customer_reserve 602706 602706 0 0.00 1 29166 50 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 4191 54 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 4106 65 0 0
sp_cancel_supplier_invoice 6814 6809 5 0.07 2 21629 632 0 5
sp_cancel_supplier_order 406 406 0 0.00 7 910104 92317 0 0
sp_cancel_write_off 10699 10699 0 0.00 1 10212 98 0 0
sp_client_order 20840 20840 0 0.00 3 86136 396 0 0
sp_customer_reserve 467705 467705 0 0.00 0 110099 153 0 0
sp_fill_shopping_cart 31932 31932 0 0.00 1 18158 37 0 0
sp_fill_shopping_cart_clo_res 467699 467699 0 0.00 0 27749 16 0 0
sp_get_clo_for_invoice 5059 5059 0 0.00 0 57709 360 0 0
sp_kill_cost_storno 17915 17915 0 0.00 0 12257 43 0 0
sp_kill_qstorno_ret_qs2qd 1223326 1223326 0 0.00 0 22598 27 0 0
sp_kill_qty_storno 643958 643958 0 0.00 0 22603 57 0 0
sp_lock_selected_doc 21283 21283 0 0.00 0 7889 6 0 0
sp_make_cost_storno 28636 28636 0 0.00 0 15986 41 0 0
sp_multiply_rows_for_pdistr 28636 28636 0 0.00 0 7079 20 0 0
sp_multiply_rows_for_qdistr 20840 20840 0 0.00 0 28779 191 0 0
sp_pay_from_customer 7302 7302 0 0.00 4 12350 88 0 0
sp_pay_to_supplier 5945 5945 0 0.00 5 11445 83 0 0
sp_payment_common 13247 13247 0 0.00 1 12335 21 0 0
sp_qd_handle_on_cancel_clo 1841 1841 0 0.00 0 8096 231 0 0
sp_qd_handle_on_invoice_upd_sts 11167 11167 0 0.00 0 22048 243 0 0
sp_qd_handle_on_reserve_upd_sts 10330 10330 0 0.00 0 13879 59 0 0
sp_reserve_write_off 10330 10330 0 0.00 6 17121 153 0 0
sp_supplier_invoice 5600 5600 0 0.00 6 94214 2919 0 0
sp_supplier_order 5510 5510 0 0.00 4 29066 1117 0 0
srv_aggregate_perf_data 98 98 0 0.00 2886 427045 21646 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1628 171493 55160 0 0
srv_find_qd_qs_mism 1111077 1111077 0 0.00 0 16160 4 0 0
srv_make_invnt_saldo 560 560 0 0.00 26 108768 1036 0 0
srv_make_money_saldo 705 705 0 0.00 0 7440 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 167323 493132 296521 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5065 5065 0 0.00 0 28092 150 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 10924 107 0 0
t$perf-norm:sp_cancel_client_order 1841 1841 0 0.00 0 11248 27 0 0
t$perf-norm:sp_cancel_customer_reserve 734 734 0 0.00 0 1277 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 1072 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 6765 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 1560 18 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 14016 1195 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 2413 10 0 0
t$perf-norm:sp_client_order 20840 20840 0 0.00 0 24116 18 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 1954 8 0 0
t$perf-norm:sp_pay_from_customer 7302 7302 0 0.00 0 11234 11 0 0
t$perf-norm:sp_pay_to_supplier 5945 5945 0 0.00 0 11183 11 0 0
t$perf-norm:sp_reserve_write_off 10330 10330 0 0.00 0 6494 10 0 0
t$perf-norm:sp_supplier_invoice 5600 5600 0 0.00 0 3976 14 0 0
t$perf-norm:sp_supplier_order 5510 5510 0 0.00 0 5585 12 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 187 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 1615 31 0 0
t$perf-norm:srv_make_invnt_saldo 560 560 0 0.00 0 281 7 0 0
t$perf-norm:srv_make_money_saldo 705 705 0 0.00 0 1392 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10316 10118 198 1.91 0 5249 18 0 198
v_all_customers 38410 38410 0 0.00 0 14 0 0 0
v_all_suppliers 31064 31064 0 0.00 0 142 0 0 0
v_all_wares 41680 41680 0 0.00 0 116 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 238 5 0 0
v_cancel_client_order 3682 3682 0 0.00 0 5502 4 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 1084 5 0 0
v_cancel_customer_reserve 1468 1468 0 0.00 0 3437 8 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 4069 11 0 0
v_cancel_supplier_invoice 828 818 10 1.20 0 434 11 0 10
v_cancel_supplier_order 812 812 0 0.00 0 332 2 0 0
v_cancel_write_off 2026 2026 0 0.00 0 569 10 0 0
v_max_id_clo_ord 5507 5507 0 0.00 0 4567 2 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 1964 11 0 0
v_max_id_ord_sup 5585 5585 0 0.00 0 3638 3 0 0
v_max_non_paid_invoice 1505 1505 0 0.00 0 576 2 0 0
v_max_non_paid_realizn 3787 3787 0 0.00 0 4022 3 0 0
v_min_id_clo_ord 5507 5507 0 0.00 0 5685 5 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 6507 21 0 0
v_min_id_ord_sup 5585 5585 0 0.00 0 6099 7 0 0
v_min_non_paid_invoice 5945 5945 0 0.00 0 11433 11 0 0
v_min_non_paid_realizn 7302 7302 0 0.00 0 12328 12 0 0
v_our_firm 10300 10300 0 0.00 0 13 1 0 0
v_reserve_write_off 20660 20660 0 0.00 0 3691 5 0 0
x_lock_depdocs_on_canc_invoice 12508 12508 0 0.00 0 19891 96 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 13711 311 0 0
x_make_qstorno_1000_1200 5510 5510 0 0.00 2 29016 1054 0 0
x_make_qstorno_1200_2000 5600 5600 0 0.00 3 94026 2776 0 0
x_make_qstorno_2100_3300 467705 467705 0 0.00 0 110098 136 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-10-2020 18:53:23 to 06-10-2020 18:53:27.

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
16:22:12 1 8192000000 8798421840 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:23:13 28989 8192000000 10324552752 10631675904 1378840208 14.406 100 91 182 0 1415896112 6200128 30855776
16:24:43 10236 8192000000 10390882912 10708615168 1451691744 15.053 100 93 186 0 1489548896 8127744 29729408
16:26:04 16091 8192000000 10443203584 10778456064 1511396672 15.575 100 84 168 3 1547435376 9675952 26362752
16:27:29 12013 8192000000 10542716720 10890440704 1605059888 16.383 100 80 160 4 1639464768 10378400 24026480
16:28:44 7458 8192000000 10613194304 10964922368 1677431456 16.996 100 79 158 0 1712339568 12430176 22477936
16:29:55 4538 8192000000 10677436864 11047825408 1744170400 17.553 100 86 173 2 1782249248 14122320 23956528
16:31:01 2078 8192000000 10722304624 11090997248 1791580960 17.945 100 92 183 0 1831440960 14934688 24925312
16:32:05 2534 8192000000 10758425552 11133046784 1820873488 18.185 100 89 177 1 1859216000 12862944 25479568
16:33:08 24758 8192000000 10775731024 11159113728 1844578864 18.378 100 67 133 1 1871989904 7563696 19847344
16:34:33 6428 8192000000 10748309760 11171860480 1849528464 18.418 100 26 52 1 1863789728 7522336 6738928
16:35:42 8870 8192000000 10752564272 11174723584 1854761040 18.461 100 17 34 0 1865296912 5891280 4644592
16:36:57 13435 8192000000 10766601664 11191660544 1866535424 18.556 100 30 60 0 1879714016 4954000 8224592
16:38:44 6691 8192000000 10787028688 11205734400 1870765680 18.590 100 29 58 1 1886594688 7405168 8423840
16:39:51 5804 8192000000 10784071472 11203497984 1869387392 18.579 100 27 54 0 1882980496 5927504 7665600
16:40:57 11459 8192000000 10773567952 11196665856 1852205200 18.440 99 16 32 2 1863998688 7574464 4219024
16:42:10 47732 8192000000 10697826480 11110735872 1705003280 17.227 93 36 72 0 1721208400 5717296 10487824
16:44:00 8218 8192000000 10666503280 11061997568 1720920368 17.360 97 31 60 2 1734217936 5316544 7981024
16:45:13 9737 8192000000 10643158704 11041632256 1694983024 17.143 97 24 48 1 1707280016 4605648 7691344
16:46:23 10648 8192000000 10673109568 11088216064 1760083360 17.685 99 34 65 2 1775883888 5493488 10307040
16:47:36 21896 8192000000 10695530688 11097600000 1782552928 17.871 100 26 51 4 1795725936 5481072 7691936
16:49:00 5983 8192000000 10714001264 11124183040 1805241952 18.057 100 24 49 2 1817860704 6027360 6591392
16:50:08 9027 8192000000 10728278736 11140222976 1821648992 18.191 100 24 48 1 1835058288 5557936 7851360
16:51:18 12761 8192000000 10742954096 11153485824 1830558832 18.264 100 28 52 2 1845037488 7302736 7175920
16:52:34 63603 8192000000 10790067072 11195211776 1861949840 18.519 100 42 85 2 1882325584 9368560 11007184
16:54:40 9466 8192000000 11037619536 11456135168 1890099248 18.747 100 37 73 3 1911944112 11705888 10138976
16:55:53 14731 8192000000 10841613408 11251564544 1895161984 18.787 99 41 80 2 1920522544 13239104 12121456
16:57:12 12721 8192000000 10826144384 11242082304 1900070192 18.827 99 47 94 0 1931630576 17908688 13651696
16:58:33 13642 8192000000 10855733056 11276800000 1929530144 19.063 100 52 104 1 1965083552 19642032 15911376
16:59:58 3990 8192000000 10883348256 11302547456 1941371008 19.158 100 58 116 0 1984316256 25126224 17819024
17:01:08 5193 8192000000 10909075936 11325255680 1961486016 19.318 100 65 130 4 2009863344 28652688 19724640
17:02:18 11209 8192000000 10919021808 11345035264 1952528304 19.247 99 45 87 1 1998042320 31229168 14284848
17:03:34 1628 8192000000 10929838528 11331637248 1946323424 19.197 100 77 153 0 2011886912 41846096 23717392
17:04:41 4226 8192000000 10921097968 11318599680 1935859872 19.114 100 85 162 2 2013476992 51689632 25927488
17:05:49 1750 8192000000 10908594928 11311521792 1923715104 19.017 100 80 160 0 2002090608 52689648 25685856
17:06:56 3443 8192000000 10893353184 11305340928 1915726384 18.953 100 57 115 1 1984651600 50727456 18197760
17:08:05 21820 8192000000 11100562608 11540099072 1902498496 18.846 99 33 67 0 1966423456 52360272 11564688
17:09:36 27301 8192000000 10870391536 11309834240 1911732864 18.921 100 25 50 4 1964850224 44075232 9042128
17:11:07 28026 8192000000 10864167744 11305463808 1877723568 18.647 98 21 42 1 1923607488 39065840 6818080
17:12:46 30412 8192000000 10840913120 11289227264 1868011504 18.568 99 23 46 0 2142103056 266167120 7924432
17:14:22 45403 8192000000 10826815312 11258204160 1889148272 18.739 99 52 105 0 1913638112 8407664 16082176
17:16:08 171493 8192000000 10827760960 11292610560 1835499360 18.304 96 25 50 0 1854110416 12313344 6297712
17:20:00 95062 8192000000 10835908400 11282616320 1868167936 18.569 98 13 26 0 1891874352 19884672 3821744
17:22:46 99875 8192000000 11053546416 11512545280 1871565648 18.597 97 40 77 2 1906712864 22982496 12164720
17:25:38 56601 8192000000 10838625648 11304148992 1908169456 18.892 99 16 32 1 1930641744 17742848 4729440
17:27:35 96042 8192000000 10834215568 11285250048 1830211056 18.261 95 12 24 2 1846409536 12959344 3239136
17:30:11 59588 8192000000 10864110688 11302346752 1901197872 18.836 98 37 74 0 1924742240 13028672 10515696
17:32:14 48506 8192000000 10872571984 11310702592 1925564112 19.031 99 37 75 1 1944087024 8315088 10207824
17:34:08 42962 8192000000 10869867232 11328892928 1947653584 19.208 100 25 50 2 1974782400 19747920 7380896
17:35:51 58490 8192000000 11128479328 11564494848 1942297040 19.165 99 43 86 0 1966339136 12349600 11692496
17:37:50 125438 8192000000 10879679120 11326328832 1897846800 18.809 97 13 25 0 1918233056 16622080 3764176
17:40:56 45872 8192000000 10874903904 11332120576 1957023744 19.282 100 25 49 1 1974105088 9085136 7996208
17:42:43 40717 8192000000 10888608016 11339001856 1958625264 19.295 100 41 82 2 1984461296 14510832 11325200
17:44:24 61084 8192000000 10835070576 11295555584 1902243904 18.844 97 17 34 3 1916786080 9632464 4909712
17:46:26 44204 8192000000 10840350944 11308965888 1914462144 18.942 98 11 22 0 1930151200 12041408 3647648
17:48:46 68999 8192000000 10862968704 11335835648 1925416320 19.030 99 17 34 0 1942321760 11895968 5009472
17:51:06 85394 8192000000 10859756064 11310931968 1850145392 18.423 95 15 28 2 1864885248 11202592 3537264
17:53:37 108345 8192000000 10850180512 11315326976 1884398736 18.701 97 16 32 1 1907500112 19438112 3663264
17:56:26 62956 8192000000 11046686224 11515281408 1862550944 18.524 97 15 30 3 1890263456 24187216 3525296
17:58:37 50133 8192000000 10858911328 11303178240 1896072336 18.795 98 17 34 1 1909989488 8595552 5321600
18:00:31 26470 8192000000 10862605424 11304161280 1903399744 18.854 99 50 100 5 1939796704 22108624 14288336
18:02:04 75169 8192000000 10834219008 11289833472 1906732960 18.880 99 20 40 0 1918064560 6148336 5183264
18:04:20 60049 8192000000 10824088256 11292389376 1881297408 18.676 98 12 24 0 1896127664 11746720 3083536
18:06:20 65858 8192000000 10811961104 11263037440 1865220608 18.546 98 11 22 0 2097617344 229496736 2900000
18:08:29 42920 8192000000 10815816560 11281825792 1899786128 18.825 100 12 24 2 1917807312 14294640 3726544
18:10:20 66239 8192000000 10821297328 11277832192 1874670368 18.622 98 13 26 0 1886801376 8684880 3446128
18:12:36 151333 8192000000 10856736688 11311489024 1889394864 18.741 98 12 24 1 1911590592 19184096 3011632
18:16:07 72379 8192000000 10847005472 11311693824 1913264832 18.933 99 10 20 0 1927283168 11306192 2712144
18:18:21 70898 8192000000 10820883712 11289710592 1841205024 18.351 96 11 22 0 1857055520 13355696 2494800
18:20:33 72127 8192000000 10852324736 11302240256 1898981696 18.818 99 27 53 1 1919726320 13703024 7041600
18:22:49 107976 8192000000 10860083232 11305758720 1913877936 18.938 99 38 76 1 1936363072 11697504 10787632
18:25:39 54431 8192000000 10848848096 11316453376 1942943232 19.170 100 13 26 0 1955172912 8671984 3557696
18:27:33 33093 8192000000 10847143680 11316936704 1952186512 19.244 100 15 30 0 2192036048 235655760 4193776
18:29:09 68952 8192000000 10875585536 11341139968 1950346080 19.229 100 13 26 2 1964461152 10680032 3435040
18:31:55 46632 8192000000 10835366768 11299676160 1869379232 18.579 96 14 28 0 1880845392 7487664 3978496
18:33:42 41565 8192000000 10832571440 11274604544 1824157072 18.212 95 26 52 1 1845190640 14017968 7015600
18:35:28 60403 8192000000 11082632224 11539365888 1919759904 18.985 100 24 48 0 1946351072 19466048 7125120
18:37:58 89134 8192000000 10841256848 11295158272 1878062704 18.649 98 14 28 1 1899547056 17771168 3713184
18:40:27 77667 8192000000 10823000192 11289419776 1850050000 18.423 97 16 32 0 1875111648 20552368 4509280
18:43:12 40396 8192000000 10817405904 11277312000 1880667664 18.670 98 20 40 0 1892344384 6313680 5363040
18:44:53 73809 8192000000 10794183760 11253813248 1833339712 18.287 97 9 18 0 1845124672 9315344 2469616
18:47:07 50877 8192000000 10799843936 11265363968 1876235024 18.635 99 12 24 1 1889038128 9811104 2992000
18:49:01 92181 8192000000 10808860480 11269464064 1822854512 18.201 96 19 38 0 1837833456 9754384 5224560
18:51:45 48640 8192000000 10458621904 10854842368 1512610752 15.586 81 28 55 0 1533126928 14464448 6051728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 06-10-2020 18:53:29 to 06-10-2020 18:53:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 99 v_add_invoice_to_stock 335544517
user_exc 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-10-2020 18:53:44 to 06-10-2020 18:53:46.

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          263588
MON$OLDEST_ACTIVE               263589
MON$OLDEST_SNAPSHOT             263589
MON$NEXT_TRANSACTION            263589
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                       2014144
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.33368 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33368 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33368 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33368 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'

06.10.20 18:53:48. There are 300 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-10-06 18:53:51 NO_SEVERE_ERRORS_FOUND
06.10.20 18:53:53. End of report.