Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.08.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 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 16848088 kB
MemAvailable 30967092 kB
Buffers 0 kB
Cached 14850188 kB
SwapCached 5984 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 18:51:55.0440
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/51132
TRN_ID                          215181
ATT_ID                          503
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: 8349.73 29615 1001968
customer order: creation 153.55 418 18426
customer order: refuse 13.58 574 1630
order to supplier: creation 41.05 1638 4926
order to supplier: removal 3.06 154494 367
invoice (draft): creation 42.47 4919 5096
invoice (draft): removal 46.83 1258 5620
invoice accept: apply 38.23 24182 4587
invoice accept: cancel 42.01 1233 5041
customer reserve: creation 3547.37 235 425684
customer reserve: removal 4156.87 86 498824
realization accept: apply 74.28 233 8913
realization accept: cancel 74.38 164 8926
payment from customer: creation 52.89 167 6347
payment from customer: removal 5.30 124 636
payment to supplier: creation 43.63 201 5235
payment to supplier: removal 3.02 131 362
service: total inventory turnovers 4.51 1145 541
service: total monetary turnovers 6.10 65 732
service: aggregate perf. data 0.59 40844 71
service: refresh index statistics 0.03 360187 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-08-2020 18:53:17 to 21-08-2020 18:53:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2651
TEST_TIME 2 3108
TEST_TIME 3 3076
TEST_TIME 4 3546
TEST_TIME 5 4011
TEST_TIME 6 4318
TEST_TIME 7 4584
TEST_TIME 8 4550
TEST_TIME 9 4501
TEST_TIME 10 4992
TEST_TIME 11 4913
TEST_TIME 12 5189
TEST_TIME 13 5293
TEST_TIME 14 5330
TEST_TIME 15 5352
TEST_TIME 16 5400
TEST_TIME 17 5724
TEST_TIME 18 5708
TEST_TIME 19 5690
TEST_TIME 20 5625
TEST_TIME 21 6067
TEST_TIME 22 6026
TEST_TIME 23 6063
TEST_TIME 24 6039
TEST_TIME 25 6064
TEST_TIME 26 6104
TEST_TIME 27 6216
TEST_TIME 28 6501
TEST_TIME 29 6669
TEST_TIME 30 6802
TEST_TIME 31 7205
TEST_TIME 32 7332
TEST_TIME 33 7535
TEST_TIME 34 7468
TEST_TIME 35 7520
TEST_TIME 36 7674
TEST_TIME 37 7752
TEST_TIME 38 7981
TEST_TIME 39 8103
TEST_TIME 40 8246
TEST_TIME 41 8693
TEST_TIME 42 9007
TEST_TIME 43 8941
TEST_TIME 44 8929
TEST_TIME 45 8901
TEST_TIME 46 8747
TEST_TIME 47 8666
TEST_TIME 48 8509
TEST_TIME 49 8539
TEST_TIME 50 8613
TEST_TIME 51 8571
TEST_TIME 52 8495
TEST_TIME 53 8420
TEST_TIME 54 8445
TEST_TIME 55 8404
TEST_TIME 56 8361
TEST_TIME 57 8340
TEST_TIME 58 8257
TEST_TIME 59 8288
TEST_TIME 60 8218
TEST_TIME 61 8232
TEST_TIME 62 8244
TEST_TIME 63 8166
TEST_TIME 64 8209
TEST_TIME 65 8171
TEST_TIME 66 8114
TEST_TIME 67 8099
TEST_TIME 68 8077
TEST_TIME 69 8020
TEST_TIME 70 8038
TEST_TIME 71 8054
TEST_TIME 72 8018
TEST_TIME 73 8053
TEST_TIME 74 8038
TEST_TIME 75 8011
TEST_TIME 76 8118
TEST_TIME 77 8141
TEST_TIME 78 8200
TEST_TIME 79 8214
TEST_TIME 80 8305
TEST_TIME 81 8274
TEST_TIME 82 8249
TEST_TIME 83 8358
TEST_TIME 84 8324
TEST_TIME 85 8386
TEST_TIME 86 8352
TEST_TIME 87 8290
TEST_TIME 88 8248
TEST_TIME 89 8280
TEST_TIME 90 8278
TEST_TIME 91 8295
TEST_TIME 92 8258
TEST_TIME 93 8210
TEST_TIME 94 8218
TEST_TIME 95 8224
TEST_TIME 96 8219
TEST_TIME 97 8247
TEST_TIME 98 8310
TEST_TIME 99 8325
TEST_TIME 100 8335
TEST_TIME 101 8366
TEST_TIME 102 8420
TEST_TIME 103 8384
TEST_TIME 104 8443
TEST_TIME 105 8477
TEST_TIME 106 8426
TEST_TIME 107 8510
TEST_TIME 108 8544
TEST_TIME 109 8488
TEST_TIME 110 8443
TEST_TIME 111 8426
TEST_TIME 112 8410
TEST_TIME 113 8411
TEST_TIME 114 8381
TEST_TIME 115 8351
TEST_TIME 116 8335
TEST_TIME 117 8316
TEST_TIME 118 8328
TEST_TIME 119 8294
TEST_TIME 120 8358

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-08-2020 18:53:20 to 21-08-2020 18:53:23.

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 1394283 1394283 0 0.00 0 22513 9 0 0
doc_list_biud 1394282 1394282 0 0.00 0 49882 39 0 0
sp_add_invoice_to_stock 4626 4587 39 0.84 13 250634 24183 0 39
sp_cancel_adding_invoice 5041 5041 0 0.00 5 76823 1234 0 0
sp_cancel_client_order 1630 1630 0 0.00 4 30769 575 0 0
sp_cancel_customer_reserve 498824 498824 0 0.00 1 49884 86 0 0
sp_cancel_pay_from_customer 636 636 0 0.00 3 3122 124 0 0
sp_cancel_pay_to_supplier 362 362 0 0.00 3 5316 131 0 0
sp_cancel_supplier_invoice 5626 5620 6 0.10 2 33375 1259 0 6
sp_cancel_supplier_order 367 367 0 0.00 6 1342031 154494 0 0
sp_cancel_write_off 8926 8926 0 0.00 1 22514 164 0 0
sp_client_order 18426 18426 0 0.00 4 26018 418 0 0
sp_customer_reserve 425684 425684 0 0.00 0 131201 235 0 0
sp_fill_shopping_cart 28439 28439 0 0.00 1 35408 59 0 0
sp_fill_shopping_cart_clo_res 425671 425671 0 0.00 0 13279 17 0 0
sp_get_clo_for_invoice 4574 4574 0 0.00 0 38789 390 0 0
sp_kill_cost_storno 14965 14965 0 0.00 0 22511 101 0 0
sp_kill_qstorno_ret_qs2qd 1012561 1012561 0 0.00 0 49874 47 0 0
sp_kill_qty_storno 533894 533894 0 0.00 0 49881 101 0 0
sp_lock_selected_doc 18723 18723 0 0.00 0 8761 5 0 0
sp_make_cost_storno 25069 25069 0 0.00 0 18254 73 0 0
sp_multiply_rows_for_pdistr 25069 25069 0 0.00 0 18160 45 0 0
sp_multiply_rows_for_qdistr 18426 18426 0 0.00 0 18901 176 0 0
sp_pay_from_customer 6347 6347 0 0.00 4 42126 167 0 0
sp_pay_to_supplier 5235 5235 0 0.00 5 31115 202 0 0
sp_payment_common 11582 11582 0 0.00 1 41981 46 0 0
sp_qd_handle_on_cancel_clo 1630 1630 0 0.00 0 30759 529 0 0
sp_qd_handle_on_invoice_upd_sts 9615 9615 0 0.00 0 15529 507 0 0
sp_qd_handle_on_reserve_upd_sts 8912 8912 0 0.00 0 18940 73 0 0
sp_reserve_write_off 8913 8913 0 0.00 6 21655 234 0 0
sp_supplier_invoice 5096 5096 0 0.00 7 80634 4919 0 0
sp_supplier_order 4926 4926 0 0.00 5 39318 1638 0 0
srv_aggregate_perf_data 71 71 0 0.00 3493 640203 40845 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1022 134252 37690 0 0
srv_find_qd_qs_mism 957802 957802 0 0.00 0 16901 7 0 0
srv_make_invnt_saldo 541 541 0 0.00 48 31214 1145 0 0
srv_make_money_saldo 732 732 0 0.00 1 14627 65 0 0
srv_recalc_idx_stat 4 4 0 0.00 58556 627725 360187 0 0
t$perf-abend:sp_add_invoice_to_stock 39 39 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4587 4587 0 0.00 0 11845 293 0 0
t$perf-norm:sp_cancel_adding_invoice 358 358 0 0.00 0 26660 218 0 0
t$perf-norm:sp_cancel_client_order 1630 1630 0 0.00 0 6026 23 0 0
t$perf-norm:sp_cancel_customer_reserve 616 616 0 0.00 0 5281 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 636 636 0 0.00 0 5006 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 362 362 0 0.00 0 1019 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 2566 30 0 0
t$perf-norm:sp_cancel_supplier_order 367 367 0 0.00 0 36288 2801 0 0
t$perf-norm:sp_cancel_write_off 903 903 0 0.00 0 2683 13 0 0
t$perf-norm:sp_client_order 18426 18426 0 0.00 0 17508 29 0 0
t$perf-norm:sp_customer_reserve 953 953 0 0.00 0 2559 10 0 0
t$perf-norm:sp_pay_from_customer 6347 6347 0 0.00 0 5148 17 0 0
t$perf-norm:sp_pay_to_supplier 5235 5235 0 0.00 0 4411 17 0 0
t$perf-norm:sp_reserve_write_off 8913 8913 0 0.00 0 12703 21 0 0
t$perf-norm:sp_supplier_invoice 5096 5096 0 0.00 0 9224 38 0 0
t$perf-norm:sp_supplier_order 4926 4926 0 0.00 0 4645 33 0 0
t$perf-norm:srv_aggregate_perf_data 71 71 0 0.00 0 876 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 367 16 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 1182 58 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 1714 40 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 5 0 0
v_add_invoice_to_stock 9224 9146 78 0.84 0 9941 21 0 78
v_all_customers 33586 33586 0 0.00 0 176 1 0 0
v_all_suppliers 27562 27562 0 0.00 0 24 1 0 0
v_all_wares 36850 36850 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 716 716 0 0.00 0 9480 29 0 0
v_cancel_client_order 3260 3260 0 0.00 0 10627 14 0 0
v_cancel_customer_prepayment 1272 1272 0 0.00 0 2741 8 0 0
v_cancel_customer_reserve 1232 1232 0 0.00 0 9770 19 0 0
v_cancel_payment_to_supplier 724 724 0 0.00 0 1389 7 0 0
v_cancel_supplier_invoice 744 732 12 1.61 0 10392 35 0 12
v_cancel_supplier_order 734 734 0 0.00 0 1078 8 0 0
v_cancel_write_off 1806 1806 0 0.00 0 8174 35 0 0
v_max_id_clo_ord 4926 4926 0 0.00 0 947 2 0 0
v_max_id_clo_res 953 953 0 0.00 0 5986 14 0 0
v_max_id_ord_sup 5088 5088 0 0.00 0 3952 5 0 0
v_max_non_paid_invoice 1468 1468 0 0.00 0 717 4 0 0
v_max_non_paid_realizn 3494 3494 0 0.00 0 2933 5 0 0
v_min_id_clo_ord 4926 4926 0 0.00 0 17619 19 0 0
v_min_id_clo_res 953 953 0 0.00 0 367 2 0 0
v_min_id_ord_sup 5088 5088 0 0.00 0 6768 10 0 0
v_min_non_paid_invoice 5235 5235 0 0.00 0 30712 34 0 0
v_min_non_paid_realizn 6347 6347 0 0.00 0 9900 22 0 0
v_our_firm 8970 8970 0 0.00 0 20 1 0 0
v_reserve_write_off 17824 17824 0 0.00 0 10909 11 0 0
x_lock_depdocs_on_canc_invoice 10295 10295 0 0.00 0 31244 190 0 0
x_lock_depdocs_on_canc_sup_ord 367 367 0 0.00 0 15006 600 0 0
x_make_qstorno_1000_1200 4926 4926 0 0.00 2 35567 1497 0 0
x_make_qstorno_1200_2000 5096 5096 0 0.00 4 80614 4716 0 0
x_make_qstorno_2100_3300 425684 425684 0 0.00 0 131171 213 0 0

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

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:21:58 2 8192000000 8798382528 8808857600 5057520 0.061 1 1 2 0 5175568 55488 62560
16:22:59 23057 8192000000 10345495168 10659090432 1406143360 14.650 100 94 188 0 1443299680 6232096 30924224
16:24:28 17984 8192000000 10411075520 10735972352 1468827568 15.203 100 93 186 0 1506869648 7867920 30174160
16:25:51 24250 8192000000 10470915776 10808995840 1527477184 15.715 100 86 172 1 1563632896 8943616 27212096
16:27:21 16252 8192000000 10533509504 10881777664 1592709840 16.277 100 76 151 0 1625133024 9599056 22824128
16:28:41 12902 8192000000 10588294544 10956382208 1652662208 16.787 100 66 132 3 1682707328 10503248 19541872
16:30:02 8634 8192000000 10662810592 11035500544 1726598912 17.407 100 76 152 2 1761548176 13345856 21603408
16:31:14 5843 8192000000 10705117152 11081256960 1770075856 17.768 100 78 156 1 1805783440 12846496 22861088
16:32:23 3242 8192000000 10749584928 11128926208 1809311232 18.090 100 81 161 1 1844551248 12657472 22582544
16:33:29 1422 8192000000 10775080160 11156557824 1837276480 18.319 100 83 164 3 1873044768 12470144 23298144
16:34:32 2224 8192000000 10803656864 11189047296 1860864000 18.510 100 95 190 2 1901725264 14034768 26826496
16:35:39 2490 8192000000 10807098384 11198959616 1871694992 18.598 100 86 173 2 1907160368 9748064 25717312
16:36:43 6022 8192000000 10770524304 11196391424 1867368256 18.563 100 30 60 3 1884848208 8761472 8718480
16:37:50 3558 8192000000 10783879440 11206320128 1878111632 18.650 100 40 80 1 1898181984 8225488 11844864
16:38:58 8705 8192000000 10782053488 11214516224 1883440240 18.693 100 25 50 2 1897561024 7060320 7060464
16:40:08 48255 8192000000 10804081728 11230040064 1890369232 18.749 100 34 66 1 1905422112 5194848 9858032
16:41:59 5756 8192000000 10792670672 11227353088 1889396032 18.741 100 24 48 2 1903333952 6706432 7231488
16:43:05 8765 8192000000 10810780032 11236536320 1876473808 18.637 99 12 24 2 1885750800 5369536 3907456
16:44:17 44246 8192000000 10755108496 11165114368 1796920224 17.989 96 22 44 1 1807668864 4857184 5891456
16:46:03 4484 8192000000 10765769584 11174780928 1848046272 18.406 100 36 71 0 1865490000 7101728 10342000
16:47:08 6607 8192000000 10679937904 11090780160 1704335728 17.221 95 22 44 1 1715122992 4747872 6039392
16:48:18 10931 8192000000 10705408720 11107708928 1774123168 17.801 99 20 40 1 1785201760 5265088 5813504
16:49:33 16087 8192000000 10715812560 11114971136 1758933936 17.676 97 48 94 0 1779272384 6490080 13848368
16:50:53 6489 8192000000 10734297264 11152371712 1824297552 18.213 100 25 50 2 1837759648 5915088 7547008
16:52:01 5707 8192000000 10799729584 11207081984 1873238192 18.610 100 47 92 0 1893018048 5605472 14174384
16:53:21 6719 8192000000 10817994464 11221217280 1883753664 18.695 100 47 96 2 1904915872 8121280 13040928
16:54:31 42432 8192000000 10828101984 11240148992 1893516592 18.774 100 43 86 2 1914769424 9098256 12154576
16:56:31 26193 8192000000 10842070736 11265273856 1912986336 18.931 100 35 70 2 1934073680 10533072 10554272
16:58:02 16096 8192000000 10865752528 11283558400 1929295216 19.061 100 53 106 2 1957822912 13306976 15220720
16:59:30 13292 8192000000 10878142320 11297849344 1944512896 19.183 100 61 115 5 1974400320 12694976 17192448
17:00:47 7241 8192000000 10903230832 11319037952 1954451840 19.262 100 73 146 1 1994211328 18544016 21215472
17:01:59 8365 8192000000 10901361808 11319992320 1958342256 19.293 100 57 112 3 1991791872 16999504 16450112
17:03:21 26715 8192000000 10915871040 11348082688 1973296368 19.412 100 48 97 1 2007459008 20136496 14026144
17:04:52 5583 8192000000 10941225712 11366883328 1994264160 19.577 100 57 114 1 2033044256 22113408 16666688
17:06:03 6338 8192000000 10946819808 11374190592 1997928560 19.606 100 54 103 3 2035599264 22515280 15155424
17:07:14 2190 8192000000 10962922672 11387465728 2004713408 19.660 100 65 130 1 2051815472 27040464 20061600
17:08:28 2877 8192000000 10970173616 11391537152 2001585568 19.635 100 75 149 1 2054196880 31285264 21326048
17:09:38 5187 8192000000 10985075152 11404443648 2013858256 19.732 100 78 155 1 2067644496 29608208 24178032
17:10:49 19283 8192000000 10970592304 11406299136 2009461456 19.697 100 50 100 0 2058029632 32758432 15809744
17:12:18 4988 8192000000 10971303312 11405639680 2007472768 19.682 100 48 95 2 2056964240 34161616 15329856
17:13:32 7178 8192000000 10968171456 11389214720 2000007328 19.623 100 71 141 0 2054508208 32817136 21683744
17:14:50 3452 8192000000 10964049984 11384561664 1979648176 19.462 99 77 152 4 2041478880 37870336 23960368
17:16:03 2777 8192000000 10965905920 11374972928 1941638848 19.160 97 82 164 1 2007728912 41684176 24405888
17:17:13 2582 8192000000 10974330432 11374424064 1971503696 19.397 99 84 165 1 2042062656 45571760 24987200
17:18:23 10662 8192000000 10962595472 11376156672 1967921408 19.369 100 70 140 1 2042543792 50849264 23773120
17:19:40 2282 8192000000 10968662000 11374403584 1965194592 19.347 100 78 151 1 2044418752 54502160 24722000
17:20:47 1019 8192000000 10997965200 11403575296 1991258448 19.554 100 94 185 2 2075522528 54631840 29632240
17:21:56 1662 8192000000 11004460416 11411976192 1989847424 19.543 100 92 183 0 2080583392 62519344 28216624
17:23:04 15717 8192000000 10964584688 11406725120 1988474416 19.532 100 51 102 1 2058497360 53434320 16588624
17:24:24 12222 8192000000 10956078064 11398238208 1976380112 19.436 99 34 69 2 2028856880 41822016 10654752
17:25:53 1869 8192000000 10988249168 11403046912 1993037120 19.568 100 77 153 2 2065517120 48537392 23942608
17:27:02 7224 8192000000 10983622704 11413131264 1990974192 19.551 100 55 109 0 2063255920 54653760 17627968
17:28:15 13346 8192000000 10966009712 11402334208 1971788304 19.400 99 45 90 0 2036098704 48912864 15397536
17:29:32 21016 8192000000 10942573728 11404902400 1970511760 19.390 100 28 56 1 2027876416 47387728 9976928
17:31:03 18846 8192000000 10916344336 11364622336 1913941456 18.938 98 18 35 2 1967880128 47380784 6557888
17:32:27 29219 8192000000 10933158864 11373977600 1954670928 19.264 100 32 63 2 2009169760 44072912 10425920
17:34:18 49442 8192000000 10917031056 11358793728 1947072784 19.203 99 37 69 0 1977234080 19907120 10254176
17:36:14 134251 8192000000 10884668336 11346407424 1911013632 18.915 97 20 41 1 1935857120 19129312 5714176
17:41:13 66630 8192000000 10861885024 11319660544 1889495440 18.742 97 24 46 0 1916061808 19430000 7136368
17:43:24 38006 8192000000 10875832976 11324280832 1910431232 18.910 98 30 62 0 1930721344 11351760 8938352
17:45:14 40155 8192000000 10865905808 11332943872 1927338160 19.046 99 19 38 0 1944350752 10780032 6232560
17:46:56 23618 8192000000 10903572528 11335569408 1957595904 19.287 100 53 105 0 1980644816 8068672 14980240
17:48:20 118712 8192000000 10870159808 11344216064 1878173712 18.650 96 12 24 0 1897567856 15798896 3595248
17:51:22 26010 8192000000 10865559696 11330301952 1939448976 19.142 100 13 24 0 1958146240 15629072 3068192
17:52:51 36750 8192000000 10871764144 11349491712 1946323872 19.197 100 10 20 0 1966869408 17538384 3007152
17:54:30 112499 8192000000 10898773872 11363450880 1943858944 19.178 99 24 48 1 1962374864 11708768 6807152
17:57:32 8999 8192000000 10883159600 11337773056 1941547632 19.159 99 32 64 0 1957310112 6768816 8993664
17:58:43 61334 8192000000 10870991824 11346522112 1943570688 19.175 99 11 22 0 1956365136 9567296 3227152
18:00:46 33551 8192000000 10866734464 11340423168 1924790768 19.025 98 24 42 1 1938864288 8112816 5960704
18:02:21 63668 8192000000 10863880608 11326484480 1892124032 18.763 97 5 10 0 1903738944 10246624 1368288
18:04:30 65093 8192000000 10864842176 11331256320 1878549664 18.653 96 19 39 1 1894851840 10090304 6211872
18:06:39 31083 8192000000 10878702592 11337756672 1944750000 19.185 100 28 56 0 1969028960 16466784 7812176
18:08:17 75292 8192000000 10876723488 11348430848 1916328832 18.957 98 17 34 2 1932323088 11009120 4985136
18:10:33 90552 8192000000 10869821136 11337703424 1915548688 18.951 98 15 30 1 1943736240 23043584 5143968
18:13:05 57564 8192000000 10886718304 11338289152 1919915200 18.986 98 39 78 1 1946825056 15804624 11105232
18:15:04 39535 8192000000 10891371536 11334455296 1930433008 19.070 99 33 66 1 1948601232 8984304 9183920
18:16:43 108686 8192000000 10879543984 11327254528 1928469152 19.055 99 30 60 1 1949671280 12417088 8785040
18:19:42 43481 8192000000 10859901200 11325759488 1929990032 19.067 100 37 73 1 1956813040 16705488 10117520
18:21:28 54087 8192000000 10855478704 11322277888 1939416288 19.142 100 18 35 0 1952522672 8199520 4906864
18:23:23 38926 8192000000 10826208656 11289030656 1884134192 18.698 98 7 14 0 1894456400 8499312 1822896
18:25:05 88062 8192000000 10826179536 11307765760 1890052976 18.746 98 13 26 0 1904246688 10531456 3662256
18:27:59 63625 8192000000 10851535840 11320119296 1880483920 18.669 97 16 32 2 1897438560 11925920 5028720
18:30:26 36073 8192000000 10889930560 11331710976 1930962688 19.075 99 33 66 1 1951496576 11058816 9475072
18:32:03 49612 8192000000 10891808640 11360542720 1955965520 19.274 100 21 42 1 1979457936 17019072 6473344
18:33:57 118951 8192000000 10882974656 11346038784 1870009520 18.584 95 15 30 1 1887832528 13313952 4509056
18:37:07 69559 8192000000 10873348448 11342934016 1885303104 18.708 96 24 48 0 1909056064 17001472 6751488
18:39:18 119026 8192000000 10885329856 11332521984 1929024912 19.059 99 15 30 1 1949973056 16489168 4458976
18:42:18 52078 8192000000 10901324976 11357040640 1968638944 19.375 100 36 71 0 1990493760 12164912 9689904
18:44:12 21562 8192000000 10864484464 11317764096 1913117952 18.932 97 37 73 3 1929623184 6012416 10492816
18:45:39 67586 8192000000 10880833600 11352199168 1941830784 19.161 99 10 20 0 1952042512 6907472 3304256
18:47:46 49074 8192000000 10881571936 11343998976 1948942304 19.218 99 22 44 1 1973523936 18449200 6132432
18:49:56 59381 8192000000 10867744320 11326799872 1937826816 19.129 99 14 26 0 1956314384 14148032 4339536

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 18:53:30 to 21-08-2020 18:53:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 39 v_add_invoice_to_stock 335544517
user_exc 6 v_cancel_supplier_invoice 335544517

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

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          214019
MON$OLDEST_ACTIVE               215517
MON$OLDEST_SNAPSHOT             215517
MON$NEXT_TRANSACTION            215517
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                       2043232
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 18:53:49. 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 18:53:51 NO_SEVERE_ERRORS_FOUND
21.08.20 18:53:53. End of report.