Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.08.2020 18: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 16914256 kB
MemAvailable 31001304 kB
Buffers 0 kB
Cached 14800764 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-18 18:51:58.3810
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/37048
TRN_ID                          227770
ATT_ID                          571
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: 8708.49 30092 1045019
customer order: creation 162.79 376 19535
customer order: refuse 13.19 547 1583
order to supplier: creation 43.27 1508 5192
order to supplier: removal 3.13 169663 376
invoice (draft): creation 43.54 4512 5225
invoice (draft): removal 49.10 1288 5892
invoice accept: apply 39.47 23562 4736
invoice accept: cancel 44.69 1384 5363
customer reserve: creation 3619.52 231 434342
customer reserve: removal 4416.79 92 530015
realization accept: apply 79.65 244 9558
realization accept: cancel 67.91 171 8149
payment from customer: creation 56.28 143 6754
payment from customer: removal 5.53 117 664
payment to supplier: creation 47.31 178 5677
payment to supplier: removal 3.43 122 411
service: total inventory turnovers 4.74 1223 569
service: total monetary turnovers 7.25 67 870
service: aggregate perf. data 0.87 22700 104
service: refresh index statistics 0.03 373721 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-08-2020 18:53:39 to 18-08-2020 18:53:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2319
TEST_TIME 2 2730
TEST_TIME 3 2812
TEST_TIME 4 2714
TEST_TIME 5 3190
TEST_TIME 6 3195
TEST_TIME 7 3694
TEST_TIME 8 3932
TEST_TIME 9 3916
TEST_TIME 10 4193
TEST_TIME 11 4332
TEST_TIME 12 4293
TEST_TIME 13 4264
TEST_TIME 14 4212
TEST_TIME 15 4193
TEST_TIME 16 4196
TEST_TIME 17 4168
TEST_TIME 18 4221
TEST_TIME 19 4199
TEST_TIME 20 4238
TEST_TIME 21 4265
TEST_TIME 22 4441
TEST_TIME 23 4733
TEST_TIME 24 4917
TEST_TIME 25 4974
TEST_TIME 26 5209
TEST_TIME 27 5645
TEST_TIME 28 5775
TEST_TIME 29 5887
TEST_TIME 30 6193
TEST_TIME 31 6127
TEST_TIME 32 6250
TEST_TIME 33 6568
TEST_TIME 34 6787
TEST_TIME 35 6986
TEST_TIME 36 7154
TEST_TIME 37 7333
TEST_TIME 38 7395
TEST_TIME 39 7950
TEST_TIME 40 8283
TEST_TIME 41 8814
TEST_TIME 42 8843
TEST_TIME 43 8727
TEST_TIME 44 8669
TEST_TIME 45 8709
TEST_TIME 46 8676
TEST_TIME 47 8751
TEST_TIME 48 8620
TEST_TIME 49 8569
TEST_TIME 50 8495
TEST_TIME 51 8478
TEST_TIME 52 8488
TEST_TIME 53 8406
TEST_TIME 54 8500
TEST_TIME 55 8468
TEST_TIME 56 8432
TEST_TIME 57 8392
TEST_TIME 58 8384
TEST_TIME 59 8620
TEST_TIME 60 8567
TEST_TIME 61 8536
TEST_TIME 62 8512
TEST_TIME 63 8512
TEST_TIME 64 8456
TEST_TIME 65 8437
TEST_TIME 66 8385
TEST_TIME 67 8386
TEST_TIME 68 8366
TEST_TIME 69 8312
TEST_TIME 70 8389
TEST_TIME 71 8405
TEST_TIME 72 8368
TEST_TIME 73 8356
TEST_TIME 74 8384
TEST_TIME 75 8473
TEST_TIME 76 8492
TEST_TIME 77 8587
TEST_TIME 78 8580
TEST_TIME 79 8656
TEST_TIME 80 8635
TEST_TIME 81 8663
TEST_TIME 82 8687
TEST_TIME 83 8685
TEST_TIME 84 8661
TEST_TIME 85 8646
TEST_TIME 86 8611
TEST_TIME 87 8587
TEST_TIME 88 8592
TEST_TIME 89 8530
TEST_TIME 90 8473
TEST_TIME 91 8495
TEST_TIME 92 8563
TEST_TIME 93 8585
TEST_TIME 94 8613
TEST_TIME 95 8600
TEST_TIME 96 8638
TEST_TIME 97 8718
TEST_TIME 98 8701
TEST_TIME 99 8750
TEST_TIME 100 8751
TEST_TIME 101 8770
TEST_TIME 102 8788
TEST_TIME 103 8872
TEST_TIME 104 8873
TEST_TIME 105 8893
TEST_TIME 106 8969
TEST_TIME 107 8889
TEST_TIME 108 8830
TEST_TIME 109 8827
TEST_TIME 110 8846
TEST_TIME 111 8829
TEST_TIME 112 8833
TEST_TIME 113 8811
TEST_TIME 114 8767
TEST_TIME 115 8787
TEST_TIME 116 8779
TEST_TIME 117 8752
TEST_TIME 118 8747
TEST_TIME 119 8711
TEST_TIME 120 8715

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-08-2020 18:53:43 to 18-08-2020 18:53:46.

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 1447324 1447324 0 0.00 0 20185 8 0 0
doc_list_biud 1447324 1447324 0 0.00 0 73436 43 0 0
sp_add_invoice_to_stock 4831 4736 95 1.96 42 296212 23563 0 95
sp_cancel_adding_invoice 5363 5363 0 0.00 4 90869 1384 0 0
sp_cancel_client_order 1583 1583 0 0.00 5 19382 548 0 0
sp_cancel_customer_reserve 530015 530015 0 0.00 1 73439 93 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 2 3407 118 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 5059 122 0 0
sp_cancel_supplier_invoice 5902 5892 10 0.16 3 24547 1288 0 10
sp_cancel_supplier_order 376 376 0 0.00 6 1601102 169663 0 0
sp_cancel_write_off 8149 8149 0 0.00 1 10055 171 0 0
sp_client_order 19535 19535 0 0.00 3 27622 377 0 0
sp_customer_reserve 434342 434342 0 0.00 0 94404 232 0 0
sp_fill_shopping_cart 29942 29942 0 0.00 1 18130 50 0 0
sp_fill_shopping_cart_clo_res 434332 434332 0 0.00 0 19319 18 0 0
sp_get_clo_for_invoice 4723 4723 0 0.00 0 23222 359 0 0
sp_kill_cost_storno 14587 14587 0 0.00 0 15564 106 0 0
sp_kill_qstorno_ret_qs2qd 1074447 1074447 0 0.00 0 73381 50 0 0
sp_kill_qty_storno 565659 565659 0 0.00 0 73435 108 0 0
sp_lock_selected_doc 19762 19762 0 0.00 0 11360 7 0 0
sp_make_cost_storno 26712 26712 0 0.00 0 15844 77 0 0
sp_multiply_rows_for_pdistr 26712 26712 0 0.00 0 10965 41 0 0
sp_multiply_rows_for_qdistr 19535 19535 0 0.00 0 17026 161 0 0
sp_pay_from_customer 6754 6754 0 0.00 4 16921 143 0 0
sp_pay_to_supplier 5677 5677 0 0.00 5 11461 178 0 0
sp_payment_common 12431 12431 0 0.00 2 16913 37 0 0
sp_qd_handle_on_cancel_clo 1583 1583 0 0.00 0 19375 499 0 0
sp_qd_handle_on_invoice_upd_sts 10086 10086 0 0.00 0 30991 543 0 0
sp_qd_handle_on_reserve_upd_sts 9558 9558 0 0.00 0 11738 74 0 0
sp_reserve_write_off 9558 9558 0 0.00 6 13464 245 0 0
sp_supplier_invoice 5225 5225 0 0.00 7 104224 4513 0 0
sp_supplier_order 5192 5192 0 0.00 5 38846 1508 0 0
srv_aggregate_perf_data 104 104 0 0.00 2073 471172 22701 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 970 135015 37807 0 0
srv_find_qd_qs_mism 999745 999745 0 0.00 0 15966 7 0 0
srv_make_invnt_saldo 569 569 0 0.00 49 49860 1223 0 0
srv_make_money_saldo 870 870 0 0.00 1 19730 67 0 0
srv_recalc_idx_stat 4 4 0 0.00 257130 654057 373722 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4736 4736 0 0.00 0 19302 268 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 4110 205 0 0
t$perf-norm:sp_cancel_client_order 1583 1583 0 0.00 0 4597 22 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 971 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 1866 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 2314 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 6108 61 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 37028 3110 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 4470 16 0 0
t$perf-norm:sp_client_order 19535 19535 0 0.00 0 21153 32 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 6138 16 0 0
t$perf-norm:sp_pay_from_customer 6754 6754 0 0.00 0 11518 18 0 0
t$perf-norm:sp_pay_to_supplier 5677 5677 0 0.00 0 4581 11 0 0
t$perf-norm:sp_reserve_write_off 9558 9558 0 0.00 0 9423 14 0 0
t$perf-norm:sp_supplier_invoice 5225 5225 0 0.00 0 17567 36 0 0
t$perf-norm:sp_supplier_order 5192 5192 0 0.00 0 8387 32 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 406 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 296 8 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 1291 38 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 1028 32 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9634 9444 190 1.97 0 13803 23 0 190
v_all_customers 35558 35558 0 0.00 0 206 1 0 0
v_all_suppliers 29206 29206 0 0.00 0 60 1 0 0
v_all_wares 39070 39070 0 0.00 0 130 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 1992 14 0 0
v_cancel_client_order 3166 3166 0 0.00 0 5183 9 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 991 7 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 13397 26 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 4647 11 0 0
v_cancel_supplier_invoice 774 754 20 2.58 0 1541 17 0 20
v_cancel_supplier_order 752 752 0 0.00 0 5643 14 0 0
v_cancel_write_off 1972 1972 0 0.00 0 9857 28 0 0
v_max_id_clo_ord 5192 5192 0 0.00 0 1864 2 0 0
v_max_id_clo_res 969 969 0 0.00 0 3818 23 0 0
v_max_id_ord_sup 5215 5215 0 0.00 0 5923 4 0 0
v_max_non_paid_invoice 1481 1481 0 0.00 0 5326 13 0 0
v_max_non_paid_realizn 3652 3652 0 0.00 0 4186 6 0 0
v_min_id_clo_ord 5192 5192 0 0.00 0 16971 32 0 0
v_min_id_clo_res 969 969 0 0.00 0 5949 12 0 0
v_min_id_ord_sup 5215 5215 0 0.00 0 6218 4 0 0
v_min_non_paid_invoice 5677 5677 0 0.00 0 10695 20 0 0
v_min_non_paid_realizn 6754 6754 0 0.00 0 6237 21 0 0
v_our_firm 9716 9716 0 0.00 0 25 1 0 0
v_reserve_write_off 19116 19116 0 0.00 0 13395 9 0 0
x_lock_depdocs_on_canc_invoice 10878 10878 0 0.00 0 21831 185 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 16961 620 0 0
x_make_qstorno_1000_1200 5192 5192 0 0.00 1 38837 1368 0 0
x_make_qstorno_1200_2000 5225 5225 0 0.00 3 104214 4349 0 0
x_make_qstorno_2100_3300 434342 434342 0 0.00 0 94401 209 0 0

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

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:01 2 8192000000 8798382528 8808857600 5057520 0.061 1 1 2 0 5175568 55488 62560
16:23:02 37503 8192000000 10383388032 10704732160 1422601136 14.796 100 90 180 2 1459837856 6437072 30799648
16:24:43 24122 8192000000 10422282256 10752385024 1474803216 15.256 100 84 168 1 1510737344 8861072 27073056
16:26:13 12220 8192000000 10464864544 10800828416 1520928112 15.658 100 83 166 0 1557534368 9711664 26894592
16:27:30 15233 8192000000 10518953792 10873430016 1582121328 16.186 100 76 152 0 1616200608 10440416 23638864
16:28:48 11668 8192000000 10576197872 10939883520 1642116192 16.698 100 79 159 0 1677362000 11995872 23249936
16:30:05 9317 8192000000 10632251376 11007004672 1698650352 17.174 100 72 143 0 1730053024 11375968 20026704
16:31:18 3715 8192000000 10695010560 11070926848 1762179808 17.702 100 77 154 1 1796133808 11955040 21998960
16:32:25 8179 8192000000 10726792464 11112333312 1793583440 17.961 100 69 136 1 1824245696 12768448 17893808
16:33:35 2584 8192000000 10763914416 11149717504 1833710816 18.290 100 75 150 1 1866758352 11663808 21383728
16:34:40 1187 8192000000 10798668320 11180716032 1857098784 18.480 100 90 179 0 1895775008 13079408 25596816
16:35:44 1704 8192000000 10808240320 11196059648 1869168544 18.578 100 85 171 1 1902150736 8999360 23982832
16:36:46 4892 8192000000 10785970624 11201867776 1870840208 18.591 100 52 106 0 1893704928 7633824 15230896
16:37:55 18830 8192000000 10800322464 11214241792 1875264240 18.627 100 17 34 0 1886210160 6046448 4899472
16:39:15 26458 8192000000 10797199072 11226533888 1885636272 18.711 100 27 53 2 1898817600 6421648 6759680
16:40:46 4545 8192000000 10805971152 11232124928 1889348192 18.741 100 30 58 0 1903569216 6012352 8208672
16:41:55 6464 8192000000 10812294128 11234590720 1891787824 18.760 100 30 57 2 1905988512 5422336 8778352
16:43:02 50432 8192000000 10789285232 11221651456 1883933024 18.697 100 35 70 2 1900295744 6562784 9799936
16:44:57 6228 8192000000 10731260576 11156660224 1813062128 18.121 99 32 64 1 1828452432 6052304 9338000
16:46:05 6754 8192000000 10714197152 11136266240 1789247104 17.926 99 17 34 2 1800378112 6235184 4895824
16:47:12 12663 8192000000 10697928656 11090927616 1748868592 17.592 97 16 32 0 1759648368 5638096 5141680
16:48:25 16392 8192000000 10710609904 11094691840 1735626816 17.482 97 39 76 2 1751104752 3947968 11529968
16:49:42 7472 8192000000 10732653488 11145633792 1815114208 18.138 100 36 72 1 1832006848 6346592 10546048
16:50:54 7926 8192000000 10761774208 11180630016 1846286960 18.392 100 14 28 1 1856255280 6234624 3733696
16:52:05 52073 8192000000 10801002272 11211587584 1873286720 18.611 100 34 67 0 1888369216 5508560 9573936
16:54:02 12836 8192000000 10833272304 11247378432 1903066608 18.851 100 37 72 1 1923773296 10197696 10508992
16:55:27 85067 8192000000 10845996224 11268825088 1918013472 18.971 100 43 86 1 1940856592 10274656 12568464
16:58:02 16812 8192000000 10861826032 11287191552 1931267088 19.077 100 44 87 0 1955556880 12378464 11911328
16:59:24 23843 8192000000 10879808048 11312472064 1945562080 19.191 100 37 73 0 1979112720 23207072 10343568
17:00:53 5264 8192000000 10907631152 11333357568 1963269536 19.332 100 55 109 3 2001976016 23275280 15431200
17:02:02 10801 8192000000 10919108864 11343572992 1973652688 19.414 100 47 89 2 2007089120 20727744 12708688
17:03:34 18802 8192000000 10951475728 11374477312 1994417872 19.579 100 69 136 2 2042120608 28698400 19004336
17:05:02 6664 8192000000 10956210624 11381256192 1996314032 19.594 100 58 114 1 2047734704 34341824 17078848
17:06:16 8212 8192000000 10968208432 11394322432 2005096144 19.663 100 56 108 0 2056983184 36342192 15544848
17:07:33 15315 8192000000 10979563808 11409899520 2017518592 19.761 100 58 116 3 2072408928 37254816 17635520
17:08:55 13652 8192000000 10983817504 11409903616 2003394624 19.649 99 56 112 1 2056983376 36652368 16936384
17:10:20 4609 8192000000 10989681120 11414171648 2017371632 19.759 100 71 143 3 2081414368 41485536 22557200
17:11:30 2529 8192000000 10992798256 11412127744 2014183040 19.734 100 77 150 2 2082312496 43708608 24420848
17:12:39 4625 8192000000 11015778576 11438235648 2033042960 19.882 100 74 148 1 2104600128 48165584 23391584
17:13:56 1485 8192000000 10990099216 11395981312 1992539168 19.564 99 86 171 2 2070810064 51254720 27016176
17:15:06 3536 8192000000 10993596800 11407388672 2001615072 19.635 100 81 162 1 2079138160 50996032 26527056
17:16:18 4885 8192000000 11001535136 11415375872 1997930384 19.606 100 78 154 1 2079795296 56465776 25399136
17:17:30 3738 8192000000 10970805520 11377262592 1956906832 19.281 100 87 172 1 2046424448 61805968 27711648
17:18:39 964 8192000000 10959764752 11363549184 1949943024 19.226 100 91 182 2 2042437200 61979184 30514992
17:19:49 4911 8192000000 10968511280 11376975872 1963307904 19.332 100 82 163 3 2050443696 60224592 26911200
17:21:02 2270 8192000000 10985228400 11388850176 1971827584 19.400 100 89 176 3 2067822000 67826176 28168240
17:22:15 2890 8192000000 10949750576 11351212032 1940500352 19.151 99 90 181 0 2029061440 61256320 27304768
17:23:23 5669 8192000000 10997385072 11410280448 1982758736 19.487 100 81 163 5 2080391792 71165856 26467200
17:24:39 3812 8192000000 11009536608 11424636928 1978161696 19.450 99 69 136 1 2069904704 70138640 21604368
17:25:49 10214 8192000000 10995199072 11427434496 1994885584 19.582 100 51 102 1 2077386832 64041216 18460032
17:27:08 10156 8192000000 10982536448 11425312768 1988217328 19.530 100 38 77 0 2063660480 61667968 13775184
17:28:24 16438 8192000000 10983550272 11419684864 1991682976 19.557 100 53 104 1 2063618656 54873808 17061872
17:29:45 31800 8192000000 10959726176 11431108608 1969448896 19.381 100 22 44 2 2043949664 66479792 8020976
17:31:39 40450 8192000000 10878616240 11345543168 1868078512 18.569 96 12 24 2 1918216128 45438704 4698912
17:33:21 81630 8192000000 10881262720 11337289728 1945673616 19.192 100 6 11 0 1959855232 12437056 1744560
17:35:53 49320 8192000000 10895622960 11347369984 1961361488 19.317 100 50 97 1 1985168288 10786304 13020496
17:37:55 69751 8192000000 10893326160 11338657792 1903598448 18.855 97 23 45 0 1921086640 10215792 7272400
17:40:07 84159 8192000000 10894397264 11349217280 1941540992 19.159 99 42 84 0 1962138800 8031488 12566320
17:42:33 135015 8192000000 10904462352 11342741504 1921563552 18.999 98 34 66 2 1945607984 14415408 9629024
17:45:59 33433 8192000000 10872700880 11337601024 1919046880 18.979 98 19 38 1 1937038160 12396640 5594640
17:47:34 25414 8192000000 10903947328 11362394112 1971587120 19.398 100 29 57 1 1995298112 14557056 9153936
17:49:20 33282 8192000000 10893433696 11357376512 1943973024 19.178 99 25 50 2 1972784080 21056064 7754992
17:50:56 45490 8192000000 10881018576 11339902976 1947613248 19.207 100 5 10 0 1960928448 12051808 1263392
17:52:46 21337 8192000000 10892932000 11347562496 1962484128 19.326 100 23 44 1 1975648672 7471328 5693216
17:54:10 27738 8192000000 10879419648 11342446592 1958246208 19.292 100 21 42 1 1972054000 8286800 5520992
17:55:39 95400 8192000000 10878416880 11351490560 1934047040 19.099 98 16 32 0 1947677648 9039312 4591296
17:58:17 993 8192000000 10901328016 11343749120 1963368064 19.333 100 69 136 2 1992156800 8931152 19857584
17:59:19 89003 8192000000 10855806272 11328311296 1884357760 18.700 96 26 53 1 1905561936 13388448 7815728
18:02:06 71054 8192000000 10830725696 11285499904 1788053696 17.916 92 17 34 1 1803478480 10944656 4480128
18:04:18 16417 8192000000 10864017424 11320524800 1922367712 19.006 99 27 54 0 1944595984 14347728 7880544
18:05:40 81832 8192000000 10874436336 11346972672 1941022576 19.155 100 12 24 0 1962342240 17510624 3809040
18:08:03 65488 8192000000 10858798096 11323109376 1861693376 18.517 96 13 26 0 1879943200 14368976 3880848
18:10:09 57407 8192000000 10876286384 11323224064 1920612144 18.992 99 19 38 0 1939222272 13071280 5538848
18:12:13 18948 8192000000 10877385136 11316916224 1918005856 18.971 99 42 84 1 1940935456 11016528 11913072
18:13:59 115182 8192000000 10841894144 11301924864 1841110112 18.350 96 14 28 1 1868973104 23193280 4669712
18:17:27 27524 8192000000 10855428704 11306962944 1914322672 18.941 99 43 86 1 1936300880 10581280 11396928
18:18:59 69402 8192000000 10848870368 11324182528 1917624336 18.968 99 10 20 0 1933922576 13044368 3253872
18:21:08 84147 8192000000 10882875872 11341266944 1925211904 19.029 99 20 40 0 1954273808 22326512 6735392
18:23:43 70979 8192000000 10839697856 11305373696 1826440720 18.230 94 12 24 1 1852136736 21976672 3719344
18:25:55 47883 8192000000 10878316080 11328036864 1905648736 18.872 98 20 40 0 1917765344 6907408 5209200
18:27:47 67209 8192000000 10864843248 11341721600 1887530528 18.726 97 14 28 0 1906956464 15464736 3961200
18:29:58 25327 8192000000 10879069728 11325923328 1892852016 18.769 97 40 80 2 1914256144 10479744 10924384
18:31:33 89312 8192000000 10871423488 11337764864 1869437344 18.580 96 19 38 1 1886269952 11454896 5377712
18:34:17 80088 8192000000 10877820032 11337576448 1892291088 18.764 97 9 18 1 1908182560 13007440 2884032
18:36:47 51470 8192000000 10874317120 11349716992 1914678000 18.944 98 14 28 1 1932785168 13408864 4698304
18:40:21 54947 8192000000 10868861888 11346849792 1938018288 19.131 100 14 28 3 1957341744 15472800 3850656
18:42:27 37368 8192000000 10883509712 11346444288 1959108880 19.299 100 25 50 2 1976234816 10314416 6811520
18:44:08 59820 8192000000 10884294704 11354996736 1949847376 19.225 99 8 16 0 1960311856 8074448 2390032
18:46:29 52580 8192000000 10852129616 11324633088 1906136160 18.876 98 10 20 0 1917469696 8186928 3146608
18:48:43 27161 8192000000 10864694416 11330486272 1941293504 19.157 100 14 28 1 1955119200 9760048 4065648
18:50:11 45018 8192000000 10848698112 11323555840 1906638640 18.880 98 29 59 2 1925826320 11616624 7571056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-08-2020 18:53:52 to 18-08-2020 18:54:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 95 v_add_invoice_to_stock 335544517
user_exc 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-08-2020 18:54:07 to 18-08-2020 18:54:09.

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          227711
MON$OLDEST_ACTIVE               227954
MON$OLDEST_SNAPSHOT             227954
MON$NEXT_TRANSACTION            227954
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                       2038304
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 ---
:171: 
:172: mscs-test-161.budzdorov.ru    Tue Aug 18 18:52:16 2020
:173:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:174: 
:175: 
:176: mscs-test-161.budzdorov.ru    Tue Aug 18 18:52:16 2020
:177:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:178: 
:179: 
:180: mscs-test-161.budzdorov.ru    Tue Aug 18 18:52:16 2020
:181:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:182: 
:183: 
:184: mscs-test-161.budzdorov.ru    Tue Aug 18 18:52:16 2020
:185:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:186: 
:187: 
:188: mscs-test-161.budzdorov.ru    Tue Aug 18 18:52:16 2020
:189:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:190: 
:191: 
:192: mscs-test-161.budzdorov.ru    Tue Aug 18 18:52:16 2020
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:194: 
:195: 
:196: mscs-test-161.budzdorov.ru    Tue Aug 18 18:52:16 2020
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:198: 
--- end of diff output ---

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

18.08.20 18:54:11. 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-18 18:54:13 NO_SEVERE_ERRORS_FOUND
18.08.20 18:54:15. End of report.