Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.08.2020 21:53
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 16678392 kB
MemAvailable 30997764 kB
Buffers 0 kB
Cached 15028056 kB
SwapCached 5960 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-16 21:51:58.6280
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/36490
TRN_ID                          221238
ATT_ID                          184
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: 8572.36 32034 1028683
customer order: creation 153.30 371 18396
customer order: refuse 13.08 552 1569
order to supplier: creation 41.43 1618 4972
order to supplier: removal 3.24 166279 389
invoice (draft): creation 41.97 4573 5036
invoice (draft): removal 48.57 1382 5828
invoice accept: apply 38.62 24378 4634
invoice accept: cancel 43.72 1257 5246
customer reserve: creation 3600.88 235 432106
customer reserve: removal 4318.62 94 518234
realization accept: apply 74.25 234 8910
realization accept: cancel 77.33 169 9280
payment from customer: creation 52.79 147 6335
payment from customer: removal 5.15 134 618
payment to supplier: creation 43.84 174 5261
payment to supplier: removal 3.18 93 382
service: total inventory turnovers 4.74 1381 569
service: total monetary turnovers 6.99 118 839
service: aggregate perf. data 0.63 36460 75
service: refresh index statistics 0.03 401030 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-08-2020 21:53:42 to 16-08-2020 21:53:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1558
TEST_TIME 2 1686
TEST_TIME 3 2491
TEST_TIME 4 3400
TEST_TIME 5 3599
TEST_TIME 6 3521
TEST_TIME 7 3563
TEST_TIME 8 3625
TEST_TIME 9 3689
TEST_TIME 10 3769
TEST_TIME 11 3691
TEST_TIME 12 3945
TEST_TIME 13 3886
TEST_TIME 14 4185
TEST_TIME 15 4454
TEST_TIME 16 4637
TEST_TIME 17 4822
TEST_TIME 18 4996
TEST_TIME 19 5048
TEST_TIME 20 5199
TEST_TIME 21 5317
TEST_TIME 22 5351
TEST_TIME 23 5820
TEST_TIME 24 6240
TEST_TIME 25 6502
TEST_TIME 26 6685
TEST_TIME 27 6759
TEST_TIME 28 6771
TEST_TIME 29 6886
TEST_TIME 30 7445
TEST_TIME 31 7373
TEST_TIME 32 7367
TEST_TIME 33 7465
TEST_TIME 34 7698
TEST_TIME 35 7947
TEST_TIME 36 8149
TEST_TIME 37 8284
TEST_TIME 38 8534
TEST_TIME 39 9091
TEST_TIME 40 9084
TEST_TIME 41 9239
TEST_TIME 42 9568
TEST_TIME 43 9392
TEST_TIME 44 9327
TEST_TIME 45 9160
TEST_TIME 46 9062
TEST_TIME 47 8967
TEST_TIME 48 8866
TEST_TIME 49 8911
TEST_TIME 50 8847
TEST_TIME 51 8821
TEST_TIME 52 8794
TEST_TIME 53 8749
TEST_TIME 54 8738
TEST_TIME 55 8708
TEST_TIME 56 8668
TEST_TIME 57 8571
TEST_TIME 58 8482
TEST_TIME 59 8439
TEST_TIME 60 8484
TEST_TIME 61 8502
TEST_TIME 62 8481
TEST_TIME 63 8527
TEST_TIME 64 8649
TEST_TIME 65 8612
TEST_TIME 66 8571
TEST_TIME 67 8512
TEST_TIME 68 8650
TEST_TIME 69 8636
TEST_TIME 70 8684
TEST_TIME 71 8693
TEST_TIME 72 8720
TEST_TIME 73 8728
TEST_TIME 74 8702
TEST_TIME 75 8724
TEST_TIME 76 8728
TEST_TIME 77 8698
TEST_TIME 78 8730
TEST_TIME 79 8835
TEST_TIME 80 8843
TEST_TIME 81 8795
TEST_TIME 82 8807
TEST_TIME 83 8780
TEST_TIME 84 8826
TEST_TIME 85 8806
TEST_TIME 86 8766
TEST_TIME 87 8712
TEST_TIME 88 8668
TEST_TIME 89 8663
TEST_TIME 90 8684
TEST_TIME 91 8665
TEST_TIME 92 8658
TEST_TIME 93 8652
TEST_TIME 94 8619
TEST_TIME 95 8569
TEST_TIME 96 8588
TEST_TIME 97 8594
TEST_TIME 98 8605
TEST_TIME 99 8638
TEST_TIME 100 8638
TEST_TIME 101 8672
TEST_TIME 102 8703
TEST_TIME 103 8683
TEST_TIME 104 8646
TEST_TIME 105 8700
TEST_TIME 106 8741
TEST_TIME 107 8761
TEST_TIME 108 8779
TEST_TIME 109 8778
TEST_TIME 110 8768
TEST_TIME 111 8717
TEST_TIME 112 8717
TEST_TIME 113 8706
TEST_TIME 114 8672
TEST_TIME 115 8682
TEST_TIME 116 8653
TEST_TIME 117 8624
TEST_TIME 118 8596
TEST_TIME 119 8592
TEST_TIME 120 8580
TEST_TIME 121 8509

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-08-2020 21:53:45 to 16-08-2020 21:53:48.

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 1427006 1427006 0 0.00 0 18848 8 0 0
doc_list_biud 1427006 1427006 0 0.00 0 121769 44 0 0
sp_add_invoice_to_stock 4712 4634 78 1.65 38 206029 24379 0 78
sp_cancel_adding_invoice 5246 5246 0 0.00 5 76633 1257 0 0
sp_cancel_client_order 1569 1569 0 0.00 5 9396 553 0 0
sp_cancel_customer_reserve 518234 518234 0 0.00 1 25557 95 0 0
sp_cancel_pay_from_customer 618 618 0 0.00 3 6597 134 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 1250 93 0 0
sp_cancel_supplier_invoice 5833 5828 5 0.08 2 121778 1383 0 5
sp_cancel_supplier_order 389 389 0 0.00 8 1386706 166279 0 0
sp_cancel_write_off 9280 9280 0 0.00 1 11105 169 0 0
sp_client_order 18396 18396 0 0.00 3 81335 371 0 0
sp_customer_reserve 432106 432106 0 0.00 0 56957 236 0 0
sp_fill_shopping_cart 28402 28402 0 0.00 1 23587 50 0 0
sp_fill_shopping_cart_clo_res 432098 432098 0 0.00 0 15676 19 0 0
sp_get_clo_for_invoice 4624 4624 0 0.00 0 36318 414 0 0
sp_kill_cost_storno 15526 15526 0 0.00 0 7573 108 0 0
sp_kill_qstorno_ret_qs2qd 1051965 1051965 0 0.00 0 121763 51 0 0
sp_kill_qty_storno 554080 554080 0 0.00 0 121766 111 0 0
sp_lock_selected_doc 18694 18694 0 0.00 0 7819 4 0 0
sp_make_cost_storno 25130 25130 0 0.00 0 15712 66 0 0
sp_multiply_rows_for_pdistr 25130 25130 0 0.00 0 7992 42 0 0
sp_multiply_rows_for_qdistr 18396 18396 0 0.00 0 81291 160 0 0
sp_pay_from_customer 6335 6335 0 0.00 4 38114 147 0 0
sp_pay_to_supplier 5261 5261 0 0.00 5 31285 174 0 0
sp_payment_common 11596 11596 0 0.00 2 38109 42 0 0
sp_qd_handle_on_cancel_clo 1569 1569 0 0.00 0 8952 509 0 0
sp_qd_handle_on_invoice_upd_sts 9870 9870 0 0.00 0 11456 550 0 0
sp_qd_handle_on_reserve_upd_sts 8910 8910 0 0.00 0 16101 86 0 0
sp_reserve_write_off 8910 8910 0 0.00 7 16132 234 0 0
sp_supplier_invoice 5036 5036 0 0.00 6 112589 4574 0 0
sp_supplier_order 4972 4972 0 0.00 5 59938 1618 0 0
srv_aggregate_perf_data 75 75 0 0.00 2342 299876 36460 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1162 167801 37138 0 0
srv_find_qd_qs_mism 984249 984249 0 0.00 0 14891 7 0 0
srv_make_invnt_saldo 569 569 0 0.00 51 45927 1381 0 0
srv_make_money_saldo 839 839 0 0.00 1 23657 118 0 0
srv_recalc_idx_stat 4 4 0 0.00 267032 480033 401031 0 0
t$perf-abend:sp_add_invoice_to_stock 78 78 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 4635 4635 0 0.00 0 14376 290 0 0
t$perf-norm:sp_cancel_adding_invoice 356 356 0 0.00 0 2692 150 0 0
t$perf-norm:sp_cancel_client_order 1569 1569 0 0.00 0 1359 17 0 0
t$perf-norm:sp_cancel_customer_reserve 577 577 0 0.00 0 545 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 618 618 0 0.00 0 781 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 3201 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 342 342 0 0.00 0 1944 29 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 41549 3259 0 0
t$perf-norm:sp_cancel_write_off 927 927 0 0.00 0 2828 16 0 0
t$perf-norm:sp_client_order 18396 18396 0 0.00 0 12313 30 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 1961 12 0 0
t$perf-norm:sp_pay_from_customer 6335 6335 0 0.00 0 5465 15 0 0
t$perf-norm:sp_pay_to_supplier 5261 5261 0 0.00 0 6611 12 0 0
t$perf-norm:sp_reserve_write_off 8910 8910 0 0.00 0 22448 17 0 0
t$perf-norm:sp_supplier_invoice 5036 5036 0 0.00 0 7045 30 0 0
t$perf-norm:sp_supplier_order 4972 4972 0 0.00 0 6262 30 0 0
t$perf-norm:srv_aggregate_perf_data 75 75 0 0.00 0 654 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 34 2 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 2200 49 0 0
t$perf-norm:srv_make_money_saldo 839 839 0 0.00 0 1581 38 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 21 9 0 0
v_add_invoice_to_stock 9404 9248 156 1.65 0 12307 21 0 156
v_all_customers 33626 33626 0 0.00 0 21 1 0 0
v_all_suppliers 27914 27914 0 0.00 0 27 1 0 0
v_all_wares 36792 36792 0 0.00 0 82 1 0 0
v_cancel_adding_invoice 712 712 0 0.00 0 8303 27 0 0
v_cancel_client_order 3138 3138 0 0.00 0 8668 11 0 0
v_cancel_customer_prepayment 1236 1236 0 0.00 0 3278 10 0 0
v_cancel_customer_reserve 1154 1154 0 0.00 0 1181 5 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 767 8 0 0
v_cancel_supplier_invoice 694 684 10 1.44 0 203 12 0 10
v_cancel_supplier_order 778 778 0 0.00 0 3091 17 0 0
v_cancel_write_off 1854 1854 0 0.00 0 1553 16 0 0
v_max_id_clo_ord 4971 4971 0 0.00 0 662 2 0 0
v_max_id_clo_res 965 965 0 0.00 0 870 8 0 0
v_max_id_ord_sup 5034 5034 0 0.00 0 1900 3 0 0
v_max_non_paid_invoice 1309 1309 0 0.00 0 9329 10 0 0
v_max_non_paid_realizn 3275 3275 0 0.00 0 1728 6 0 0
v_min_id_clo_ord 4971 4971 0 0.00 0 1968 6 0 0
v_min_id_clo_res 965 965 0 0.00 0 1782 7 0 0
v_min_id_ord_sup 5034 5034 0 0.00 0 6070 6 0 0
v_min_non_paid_invoice 5261 5261 0 0.00 0 31233 30 0 0
v_min_non_paid_realizn 6335 6335 0 0.00 0 19433 21 0 0
v_our_firm 9286 9286 0 0.00 0 50 1 0 0
v_reserve_write_off 17820 17820 0 0.00 0 4554 7 0 0
x_lock_depdocs_on_canc_invoice 10732 10732 0 0.00 0 11988 192 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 9753 624 0 0
x_make_qstorno_1000_1200 4972 4972 0 0.00 2 59911 1504 0 0
x_make_qstorno_1200_2000 5036 5036 0 0.00 3 110019 4392 0 0
x_make_qstorno_2100_3300 432106 432106 0 0.00 0 56954 212 0 0

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

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:01 2 8192000000 8798382528 8808857600 5057520 0.061 1 1 2 0 5175568 55488 62560
19:23:02 19706 8192000000 10358326512 10677637120 1421285568 14.784 100 89 179 0 1456235568 6110416 28839584
19:24:28 20651 8192000000 10410068400 10740981760 1469737632 15.211 100 88 176 0 1506628672 8156880 28734160
19:25:55 16268 8192000000 10474760176 10810621952 1525109232 15.695 100 90 180 0 1566681504 13634880 27937392
19:27:19 13709 8192000000 10526935264 10879111168 1586570480 16.224 100 75 150 2 1619933152 10276960 23085712
19:28:36 9672 8192000000 10594476208 10961084416 1656070352 16.816 100 73 145 0 1688204912 10782160 21352400
19:29:49 8871 8192000000 10641043376 11011956736 1707949376 17.252 100 72 142 3 1739408128 10815968 20642784
19:31:00 4943 8192000000 10691015664 11067715584 1757156944 17.661 100 77 150 0 1790696320 13309696 20229680
19:32:07 3241 8192000000 10736882624 11113607168 1795246320 17.975 100 82 164 2 1830798896 12864800 22687776
19:33:15 3092 8192000000 10775778640 11162943488 1837837360 18.323 100 80 160 0 1871590592 12243344 21509888
19:34:20 1662 8192000000 10798846336 11183579136 1861657328 18.517 100 92 184 1 1901263152 13037104 26568720
19:35:22 4965 8192000000 10774620832 11193290752 1860968560 18.511 100 34 68 0 1878583184 8553152 9061472
19:36:29 9800 8192000000 10768276288 11197157376 1867494176 18.564 100 15 29 2 1877522224 5452368 4575680
19:37:39 48609 8192000000 10809657488 11218046976 1880373232 18.668 100 55 107 4 1901909584 5521120 16015232
19:39:28 7513 8192000000 10781861136 11218800640 1880936016 18.673 100 22 44 3 1893696640 6587136 6173488
19:40:38 7791 8192000000 10791246112 11215589376 1875606800 18.630 99 27 52 4 1890007872 6433104 7967968
19:41:46 58957 8192000000 10786691120 11228057600 1871361824 18.595 99 22 44 1 1883270144 5131264 6777056
19:43:48 10451 8192000000 10739424144 11161702400 1790608736 17.937 97 18 36 2 1801497392 6021408 4867248
19:45:01 12917 8192000000 10676029536 11087659008 1687222976 17.078 94 20 40 2 1698136416 4999808 5913632
19:46:14 58226 8192000000 10675625008 11093364736 1690139712 17.102 94 13 26 0 1698906080 4794160 3972208
19:48:18 5191 8192000000 10744104192 11147923456 1813373008 18.123 100 39 78 3 1830928144 5867776 11687360
19:49:24 7768 8192000000 10750896016 11160309760 1811910512 18.112 99 22 44 2 1823934880 5691088 6333280
19:50:32 60860 8192000000 10754089648 11175563264 1845227488 18.383 100 38 76 0 1862156944 6168160 10761296
19:52:40 58967 8192000000 10805305104 11215872000 1866192432 18.553 100 38 75 1 1882910096 6120448 10597216
19:54:45 25133 8192000000 10842676096 11255963648 1906065600 18.875 100 58 116 0 1939136720 16399904 16671216
19:56:16 8142 8192000000 10865784640 11279421440 1923965584 19.019 100 61 122 1 1962747792 21677424 17104784
19:57:28 17110 8192000000 10881982352 11291389952 1932494432 19.087 100 58 116 2 1971691248 22814944 16381872
19:59:17 12283 8192000000 10868015456 11297439744 1932998048 19.091 100 28 56 1 1962815808 21094000 8723760
20:00:36 25358 8192000000 10890996096 11318624256 1946658432 19.200 100 39 77 1 1980249168 22184192 11406544
20:02:06 10082 8192000000 10917052288 11332886528 1958932032 19.298 100 56 109 2 2002566800 27274384 16360384
20:03:25 16144 8192000000 10907997200 11342442496 1963723824 19.336 100 36 70 2 2005000656 30317440 10959392
20:04:50 10359 8192000000 10965111200 11371589632 1987941680 19.528 100 83 166 2 2046147696 34225552 23980464
20:06:08 6170 8192000000 10961775440 11373510656 1984344112 19.499 100 70 140 1 2043646608 37985152 21317344
20:07:27 15162 8192000000 10970422432 11391107072 1995771200 19.589 100 70 139 0 2057212192 39819264 21621728
20:08:53 8007 8192000000 10991257344 11399065600 1998497616 19.611 100 83 162 0 2068971136 46453024 24020496
20:10:12 2731 8192000000 11002368480 11409649664 2007405824 19.681 100 90 177 1 2085399360 50934368 27059168
20:11:22 10297 8192000000 10991528688 11406925824 1994725488 19.581 100 80 159 0 2076566656 56925392 24915776
20:12:43 2866 8192000000 11011813456 11425542144 2007485184 19.682 100 87 174 0 2097040224 61649776 27905264
20:13:52 2556 8192000000 11022931360 11439140864 1992174992 19.561 99 85 169 1 2089745920 71275392 26295536
20:15:05 2063 8192000000 11003567472 11417612288 1995526880 19.587 100 85 170 2 2089223056 66603168 27093008
20:16:14 3193 8192000000 10966401408 11381862400 1943269552 19.173 98 78 154 2 2027478784 58668352 25540880
20:17:30 3738 8192000000 10990824496 11399249920 1966996192 19.362 99 89 176 0 2056640880 61606832 28037856
20:18:46 4394 8192000000 10990447984 11393843200 1981635040 19.478 100 86 173 2 2069708272 60837552 27235680
20:20:00 1670 8192000000 10976038304 11380785152 1965290976 19.348 99 90 178 0 2057519872 63297120 28931776
20:21:08 1540 8192000000 11005329424 11416080384 1994612784 19.580 100 85 171 5 2084213312 62069136 27531392
20:22:17 14763 8192000000 10955789568 11392057344 1971044640 19.394 100 34 67 2 2036693664 54554992 11094032
20:23:39 9107 8192000000 10983647728 11410190336 1988545312 19.532 100 61 122 3 2066478400 57665056 20268032
20:24:51 15007 8192000000 10947462272 11394711552 1943641328 19.176 99 31 61 0 2019844448 64804368 11398752
20:26:13 10662 8192000000 10960074768 11402362880 1970276368 19.388 100 40 80 1 2040289648 56986128 13027152
20:27:36 17502 8192000000 10936437088 11392823296 1962559392 19.326 100 21 42 1 2024215664 53638720 8017552
20:28:57 4639 8192000000 10967787568 11390472192 1953844448 19.257 99 73 146 3 2027227264 51658992 21723824
20:30:05 4944 8192000000 10942089904 11373953024 1953341952 19.253 100 49 97 1 2020035344 50650432 16042960
20:31:16 21395 8192000000 10898103776 11346374656 1946993680 19.203 100 23 46 0 1981180880 26494064 7693136
20:32:41 23903 8192000000 10888332800 11355873280 1945067472 19.187 100 15 30 2 1976773296 26634416 5071408
20:34:09 87721 8192000000 10877375472 11341586432 1951261696 19.237 100 19 38 4 1973323152 16396032 5665424
20:36:59 167801 8192000000 10899204720 11340832768 1923731472 19.017 98 43 83 2 1945258880 10575888 10951520
20:40:58 90084 8192000000 10880540640 11342831616 1916498144 18.959 98 15 30 2 1932394640 12252816 3643680
20:43:28 9086 8192000000 10898947360 11343351808 1963412416 19.333 100 32 63 3 1978476384 5940176 9123792
20:44:38 16109 8192000000 10880082176 11328708608 1930214624 19.069 98 53 104 2 1954006528 9085472 14706432
20:46:01 76683 8192000000 10871612112 11327520768 1929360160 19.062 99 26 51 3 1945482800 7794880 8327760
20:48:27 51273 8192000000 10868971168 11315400704 1910083424 18.907 98 19 37 2 1927696464 11693088 5919952
20:50:21 1160 8192000000 10920021264 11340091392 1964269712 19.340 100 85 169 4 2002745664 13795200 24680752
20:51:25 34191 8192000000 10934984976 11376467968 1980090400 19.465 100 38 75 1 2012442880 21085248 11267232
20:53:02 12274 8192000000 10956294000 11384393728 1994488320 19.579 100 63 125 1 2037203104 23379328 19335456
20:54:18 36215 8192000000 10915607104 11379101696 1971736208 19.399 100 16 32 2 2003154576 25977648 5440720
20:55:56 48851 8192000000 10902630896 11367731200 1973114656 19.410 100 16 32 1 1994349952 16493472 4741824
20:58:10 58302 8192000000 10881576848 11354615808 1940588576 19.151 99 20 40 0 1966701280 19922512 6190192
21:00:23 78283 8192000000 10901929120 11363872768 1913611232 18.936 97 16 32 0 1930354432 12418432 4324768
21:02:41 47766 8192000000 10911544864 11378098176 1949197280 19.220 99 12 24 0 1970372464 17769856 3405328
21:04:32 40977 8192000000 10902949504 11353968640 1960536208 19.310 100 42 84 0 1982022944 9824448 11662288
21:06:33 61954 8192000000 10867185008 11330908160 1906427536 18.878 97 22 42 4 1923944272 11367728 6149008
21:08:35 76588 8192000000 10878111584 11347267584 1915220576 18.949 98 20 40 1 1938622064 17838944 5562544
21:10:52 118459 8192000000 10877172912 11332362240 1859281456 18.497 95 6 12 0 1876754384 15832832 1640096
21:13:52 52377 8192000000 10893689568 11348189184 1910786112 18.913 98 14 28 0 1927554208 12854960 3913136
21:17:35 100064 8192000000 10892515776 11341246464 1954784416 19.265 100 26 53 0 1969869904 8024048 7061440
21:20:19 44263 8192000000 10876272832 11341549568 1953330192 19.253 100 46 91 0 1981610832 14766320 13514320
21:22:40 49927 8192000000 10852749792 11321315328 1914952208 18.946 98 14 28 1 1930724400 11888400 3883792
21:24:32 61089 8192000000 10825954464 11283075072 1850327648 18.425 96 11 22 0 1859662576 6664848 2670080
21:26:43 49538 8192000000 10825507904 11285934080 1882591936 18.686 98 6 12 2 1890953856 6699728 1662192
21:28:33 48707 8192000000 10874352336 11337248768 1943432224 19.174 100 16 32 1 1958892736 10654384 4806128
21:30:23 31704 8192000000 10873898624 11334148096 1919764752 18.985 98 31 61 2 1943632512 14847520 9020240
21:31:58 46197 8192000000 10838393008 11295772672 1882993552 18.689 98 15 30 0 1903818960 16204512 4620896
21:33:45 62132 8192000000 10862931664 11315929088 1868400208 18.571 96 31 62 1 1891742992 15043664 8299120
21:35:49 28614 8192000000 10893293760 11341713408 1949386272 19.222 100 44 87 0 1978166992 15894912 12885808
21:37:19 49702 8192000000 10886075184 11341991936 1937617488 19.128 99 16 32 1 1954154128 12030192 4506448
21:39:13 69223 8192000000 10913044144 11356921856 1920329248 18.989 98 26 51 4 1940941984 13565568 7047168
21:41:25 142599 8192000000 10879111088 11346497536 1910670336 18.912 97 16 32 0 1927322176 11385296 5266544
21:44:48 42327 8192000000 10857387344 11324178432 1934763392 19.105 99 17 34 1 1947911936 8312368 4836176
21:46:34 59805 8192000000 10870158528 11345059840 1942423408 19.166 99 18 36 1 1955101712 7741232 4937072
21:48:35 57461 8192000000 10874642896 11338006528 1944965264 19.186 99 19 38 1 1956574864 6712848 4896752
21:50:33 45880 8192000000 10876012416 11354312704 1941241328 19.157 99 11 21 0 1954213776 9698496 3273952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-08-2020 21:53:55 to 16-08-2020 21:54:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 78 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 16-08-2020 21:54:09 to 16-08-2020 21:54:11.

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

16.08.20 21:54:13. 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-16 21:54:15 NO_SEVERE_ERRORS_FOUND
16.08.20 21:54:17. End of report.