Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.08.2020 00:54
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 16701912 kB
MemAvailable 30962360 kB
Buffers 0 kB
Cached 14994004 kB
SwapCached 6008 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-22 00:51:54.4400
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/54958
TRN_ID                          218773
ATT_ID                          550
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: 8610.13 26217 1033215
customer order: creation 158.37 398 19004
customer order: refuse 12.95 515 1554
order to supplier: creation 42.62 1532 5114
order to supplier: removal 3.05 168822 366
invoice (draft): creation 42.88 4350 5146
invoice (draft): removal 50.08 1224 6009
invoice accept: apply 37.38 23013 4485
invoice accept: cancel 45.10 1214 5412
customer reserve: creation 3480.74 223 417689
customer reserve: removal 4464.00 88 535680
realization accept: apply 76.72 242 9206
realization accept: cancel 76.70 158 9204
payment from customer: creation 54.15 163 6498
payment from customer: removal 4.96 140 595
payment to supplier: creation 45.56 163 5467
payment to supplier: removal 3.06 157 367
service: total inventory turnovers 4.44 1469 533
service: total monetary turnovers 6.57 35 788
service: aggregate perf. data 0.78 23529 94
service: refresh index statistics 0.03 296909 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-08-2020 00:54:33 to 22-08-2020 00:54:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1167
TEST_TIME 2 1542
TEST_TIME 3 1755
TEST_TIME 4 3347
TEST_TIME 5 3222
TEST_TIME 6 3366
TEST_TIME 7 3503
TEST_TIME 8 4026
TEST_TIME 9 4142
TEST_TIME 10 4172
TEST_TIME 11 4068
TEST_TIME 12 3980
TEST_TIME 13 3991
TEST_TIME 14 4018
TEST_TIME 15 4188
TEST_TIME 16 4227
TEST_TIME 17 4826
TEST_TIME 18 5090
TEST_TIME 19 5119
TEST_TIME 20 5196
TEST_TIME 21 5544
TEST_TIME 22 5783
TEST_TIME 23 6312
TEST_TIME 24 6430
TEST_TIME 25 6413
TEST_TIME 26 6361
TEST_TIME 27 6458
TEST_TIME 28 6341
TEST_TIME 29 6699
TEST_TIME 30 6586
TEST_TIME 31 7091
TEST_TIME 32 7286
TEST_TIME 33 7381
TEST_TIME 34 7322
TEST_TIME 35 7371
TEST_TIME 36 7699
TEST_TIME 37 7879
TEST_TIME 38 8565
TEST_TIME 39 9178
TEST_TIME 40 9621
TEST_TIME 41 9893
TEST_TIME 42 9767
TEST_TIME 43 9680
TEST_TIME 44 9558
TEST_TIME 45 9546
TEST_TIME 46 9434
TEST_TIME 47 9282
TEST_TIME 48 9443
TEST_TIME 49 9330
TEST_TIME 50 9366
TEST_TIME 51 9226
TEST_TIME 52 9160
TEST_TIME 53 9145
TEST_TIME 54 9095
TEST_TIME 55 8971
TEST_TIME 56 8947
TEST_TIME 57 8903
TEST_TIME 58 8918
TEST_TIME 59 8882
TEST_TIME 60 8799
TEST_TIME 61 8723
TEST_TIME 62 8678
TEST_TIME 63 8684
TEST_TIME 64 8664
TEST_TIME 65 8705
TEST_TIME 66 8731
TEST_TIME 67 8658
TEST_TIME 68 8644
TEST_TIME 69 8601
TEST_TIME 70 8541
TEST_TIME 71 8570
TEST_TIME 72 8539
TEST_TIME 73 8526
TEST_TIME 74 8495
TEST_TIME 75 8403
TEST_TIME 76 8421
TEST_TIME 77 8408
TEST_TIME 78 8433
TEST_TIME 79 8426
TEST_TIME 80 8451
TEST_TIME 81 8482
TEST_TIME 82 8568
TEST_TIME 83 8570
TEST_TIME 84 8639
TEST_TIME 85 8615
TEST_TIME 86 8627
TEST_TIME 87 8628
TEST_TIME 88 8657
TEST_TIME 89 8742
TEST_TIME 90 8692
TEST_TIME 91 8633
TEST_TIME 92 8601
TEST_TIME 93 8550
TEST_TIME 94 8601
TEST_TIME 95 8588
TEST_TIME 96 8559
TEST_TIME 97 8503
TEST_TIME 98 8517
TEST_TIME 99 8563
TEST_TIME 100 8537
TEST_TIME 101 8562
TEST_TIME 102 8583
TEST_TIME 103 8598
TEST_TIME 104 8604
TEST_TIME 105 8618
TEST_TIME 106 8648
TEST_TIME 107 8671
TEST_TIME 108 8717
TEST_TIME 109 8776
TEST_TIME 110 8772
TEST_TIME 111 8773
TEST_TIME 112 8768
TEST_TIME 113 8700
TEST_TIME 114 8700
TEST_TIME 115 8648
TEST_TIME 116 8700
TEST_TIME 117 8657
TEST_TIME 118 8648
TEST_TIME 119 8634
TEST_TIME 120 8610

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-08-2020 00:54:36 to 22-08-2020 00:54:39.

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 1420513 1420513 0 0.00 0 21600 8 0 0
doc_list_biud 1420513 1420513 0 0.00 0 19769 42 0 0
sp_add_invoice_to_stock 4572 4485 87 1.90 46 219333 23014 0 87
sp_cancel_adding_invoice 5412 5412 0 0.00 5 72486 1215 0 0
sp_cancel_client_order 1554 1554 0 0.00 5 11986 516 0 0
sp_cancel_customer_reserve 535680 535680 0 0.00 1 19771 88 0 0
sp_cancel_pay_from_customer 595 595 0 0.00 3 8057 140 0 0
sp_cancel_pay_to_supplier 367 367 0 0.00 3 19386 158 0 0
sp_cancel_supplier_invoice 6018 6009 9 0.14 1 15729 1224 0 9
sp_cancel_supplier_order 366 366 0 0.00 7 1543378 168822 0 0
sp_cancel_write_off 9204 9204 0 0.00 1 9661 158 0 0
sp_client_order 19004 19004 0 0.00 4 40275 398 0 0
sp_customer_reserve 417689 417689 0 0.00 0 151097 224 0 0
sp_fill_shopping_cart 29260 29260 0 0.00 1 33610 50 0 0
sp_fill_shopping_cart_clo_res 417689 417689 0 0.00 0 43564 18 0 0
sp_get_clo_for_invoice 4485 4485 0 0.00 0 46402 374 0 0
sp_kill_cost_storno 15578 15578 0 0.00 0 12083 99 0 0
sp_kill_qstorno_ret_qs2qd 1086939 1086939 0 0.00 0 19765 47 0 0
sp_kill_qty_storno 571916 571916 0 0.00 0 19769 102 0 0
sp_lock_selected_doc 18897 18897 0 0.00 0 8052 7 0 0
sp_make_cost_storno 25656 25656 0 0.00 0 21589 74 0 0
sp_multiply_rows_for_pdistr 25656 25656 0 0.00 0 7911 35 0 0
sp_multiply_rows_for_qdistr 19004 19004 0 0.00 0 40266 169 0 0
sp_pay_from_customer 6498 6498 0 0.00 4 20218 163 0 0
sp_pay_to_supplier 5467 5467 0 0.00 5 22190 163 0 0
sp_payment_common 11965 11965 0 0.00 1 21763 44 0 0
sp_qd_handle_on_cancel_clo 1554 1554 0 0.00 0 11981 481 0 0
sp_qd_handle_on_invoice_upd_sts 9897 9897 0 0.00 0 14130 507 0 0
sp_qd_handle_on_reserve_upd_sts 9206 9206 0 0.00 0 17777 72 0 0
sp_reserve_write_off 9206 9206 0 0.00 6 19328 243 0 0
sp_supplier_invoice 5146 5146 0 0.00 6 100681 4350 0 0
sp_supplier_order 5114 5114 0 0.00 5 32749 1533 0 0
srv_aggregate_perf_data 94 94 0 0.00 2594 190452 23529 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1363 373661 48173 0 0
srv_find_qd_qs_mism 989753 989753 0 0.00 0 14712 6 0 0
srv_make_invnt_saldo 533 533 0 0.00 43 188752 1470 0 0
srv_make_money_saldo 788 788 0 0.00 0 440 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 70123 449586 296909 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4485 4485 0 0.00 0 26308 253 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 6127 172 0 0
t$perf-norm:sp_cancel_client_order 1554 1554 0 0.00 0 2101 18 0 0
t$perf-norm:sp_cancel_customer_reserve 612 612 0 0.00 0 2746 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 595 595 0 0.00 0 1318 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 367 367 0 0.00 0 5402 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 3242 28 0 0
t$perf-norm:sp_cancel_supplier_order 366 366 0 0.00 0 36812 3008 0 0
t$perf-norm:sp_cancel_write_off 922 922 0 0.00 0 1777 7 0 0
t$perf-norm:sp_client_order 19004 19004 0 0.00 0 21397 28 0 0
t$perf-norm:sp_customer_reserve 926 926 0 0.00 0 1198 8 0 0
t$perf-norm:sp_pay_from_customer 6498 6498 0 0.00 0 7737 14 0 0
t$perf-norm:sp_pay_to_supplier 5467 5467 0 0.00 0 7540 15 0 0
t$perf-norm:sp_reserve_write_off 9206 9206 0 0.00 0 8920 15 0 0
t$perf-norm:sp_supplier_invoice 5146 5146 0 0.00 0 9931 31 0 0
t$perf-norm:sp_supplier_order 5114 5114 0 0.00 0 7100 26 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 509 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 412 9 0 0
t$perf-norm:srv_make_invnt_saldo 533 533 0 0.00 0 1654 57 0 0
t$perf-norm:srv_make_money_saldo 788 788 0 0.00 0 2050 31 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 7 0 0
v_add_invoice_to_stock 9142 8968 174 1.90 0 19229 22 0 174
v_all_customers 34590 34590 0 0.00 0 30 1 0 0
v_all_suppliers 28930 28930 0 0.00 0 17 1 0 0
v_all_wares 38006 38006 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 8363 32 0 0
v_cancel_client_order 3108 3108 0 0.00 0 4144 7 0 0
v_cancel_customer_prepayment 1190 1190 0 0.00 0 3899 19 0 0
v_cancel_customer_reserve 1224 1224 0 0.00 0 1400 5 0 0
v_cancel_payment_to_supplier 734 734 0 0.00 0 19343 33 0 0
v_cancel_supplier_invoice 808 790 18 2.22 0 1206 21 0 18
v_cancel_supplier_order 732 732 0 0.00 0 1969 7 0 0
v_cancel_write_off 1844 1844 0 0.00 0 3402 16 0 0
v_max_id_clo_ord 5112 5112 0 0.00 0 4080 2 0 0
v_max_id_clo_res 926 926 0 0.00 0 28172 45 0 0
v_max_id_ord_sup 5145 5145 0 0.00 0 5256 6 0 0
v_max_non_paid_invoice 1259 1259 0 0.00 0 646 3 0 0
v_max_non_paid_realizn 3354 3354 0 0.00 0 6249 8 0 0
v_min_id_clo_ord 5112 5112 0 0.00 0 4602 10 0 0
v_min_id_clo_res 926 926 0 0.00 0 1807 10 0 0
v_min_id_ord_sup 5145 5145 0 0.00 0 5145 8 0 0
v_min_non_paid_invoice 5467 5467 0 0.00 0 21759 31 0 0
v_min_non_paid_realizn 6498 6498 0 0.00 0 20204 28 0 0
v_our_firm 9704 9704 0 0.00 0 17 1 0 0
v_reserve_write_off 18410 18410 0 0.00 0 19243 12 0 0
x_lock_depdocs_on_canc_invoice 11026 11026 0 0.00 0 18033 173 0 0
x_lock_depdocs_on_canc_sup_ord 366 366 0 0.00 0 18086 619 0 0
x_make_qstorno_1000_1200 5114 5114 0 0.00 2 32171 1412 0 0
x_make_qstorno_1200_2000 5146 5146 0 0.00 3 100645 4171 0 0
x_make_qstorno_2100_3300 417689 417689 0 0.00 0 151078 201 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-08-2020 00:54:40 to 22-08-2020 00:54:44.

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
22:21:57 2 8192000000 8798382528 8808857600 5057520 0.061 1 1 2 0 5175568 55488 62560
22:22:58 31978 8192000000 10364604880 10682970112 1413409744 14.714 100 90 180 0 1448940128 6154768 29375616
22:24:36 30774 8192000000 10427470288 10755674112 1479892832 15.300 100 92 184 1 1517917200 8701424 29322944
22:26:13 22960 8192000000 10462269088 10800001024 1520973280 15.659 100 88 176 0 1558724912 10408864 27342768
22:27:43 6564 8192000000 10527364688 10875633664 1590663168 16.260 100 79 158 0 1624798016 10416112 23718736
22:28:54 16293 8192000000 10612370272 10973872128 1663088480 16.875 100 75 149 4 1696372416 11234000 22049936
22:30:16 8760 8192000000 10658841408 11035447296 1722965056 17.377 100 70 139 0 1754371520 11923296 19483168
22:31:27 6463 8192000000 10694326752 11079954432 1762299088 17.703 100 68 136 2 1792841504 11479072 19063344
22:32:36 3304 8192000000 10738018592 11122880512 1804769232 18.053 100 79 158 1 1840655120 12821840 23064048
22:33:43 2483 8192000000 10768675792 11155595264 1834246864 18.294 100 86 169 1 1870990016 13230512 23512640
22:34:48 1967 8192000000 10794077840 11175923712 1849923504 18.422 100 90 178 1 1886661664 12693616 24044544
22:35:52 13128 8192000000 10800526416 11186978816 1860128512 18.504 100 74 148 0 1893028128 12356352 20543264
22:37:11 5890 8192000000 10805792464 11200446464 1868281712 18.570 100 73 144 3 1901164752 12723568 20159472
22:38:20 5503 8192000000 10773481808 11195867136 1874426192 18.620 100 51 100 0 1897259072 8170448 14662432
22:39:27 8549 8192000000 10798035824 11200086016 1873560432 18.613 100 38 78 1 1890440640 5800656 11079552
22:40:38 10081 8192000000 10820518352 11225497600 1886867920 18.721 100 66 131 3 1917272800 11053136 19351744
22:41:49 4199 8192000000 10815762032 11231993856 1893506016 18.774 100 46 91 2 1915565744 9300000 12759728
22:42:56 5402 8192000000 10819188576 11234660352 1891419920 18.757 100 35 68 0 1912039008 10295488 10323600
22:44:04 16508 8192000000 10835458032 11238256640 1897650416 18.807 100 75 147 1 1926396640 5517936 23228288
22:45:24 4193 8192000000 10771161088 11185446912 1816087376 18.146 97 40 79 3 1838381936 11499088 10795472
22:46:32 5849 8192000000 10665850928 11073609728 1655445712 16.810 92 25 44 4 1666054272 4733296 5875264
22:47:39 50623 8192000000 10679776496 11082899456 1681646720 17.031 94 34 62 2 1695857600 4314576 9896304
22:49:30 7359 8192000000 10716566800 11125649408 1772076112 17.784 98 41 83 0 1790946144 7066128 11803904
22:50:40 4495 8192000000 10738310944 11155697664 1825337792 18.221 100 34 67 1 1842134032 7009184 9787056
22:51:45 13239 8192000000 10774991280 11181875200 1854544816 18.459 100 17 34 0 1863873856 4580512 4748528
22:53:02 63215 8192000000 10819944464 11218006016 1881504816 18.677 100 57 113 1 1908411568 10176784 16729968
22:55:19 9001 8192000000 10828107344 11248431104 1902265120 18.845 100 53 106 1 1936674112 18830112 15578880
22:56:35 34663 8192000000 10858161520 11275571200 1920457536 18.991 100 54 109 1 1954580288 18350672 15772080
22:58:46 36429 8192000000 10867580272 11294015488 1932973168 19.091 100 42 84 2 1965655888 20082848 12599872
23:00:26 13192 8192000000 10885997728 11314008064 1945691792 19.192 100 37 73 1 1979702320 23242560 10767968
23:01:43 11502 8192000000 10897232832 11327303680 1958315536 19.293 100 42 82 2 1992343088 21916736 12110816
23:03:36 23695 8192000000 10928755824 11356479488 1980855152 19.471 100 42 84 1 2023201680 29652640 12693888
23:05:07 3757 8192000000 10965649760 11382497280 1997661136 19.604 100 64 124 2 2052178160 35297936 19219088
23:06:20 7256 8192000000 10949098464 11373694976 1986440480 19.516 100 57 115 2 2043363200 38005632 18917088
23:07:37 5375 8192000000 10981368576 11406733312 2001546896 19.635 100 63 126 0 2067770128 45831184 20392048
23:08:54 3624 8192000000 10982689680 11399696384 2005361520 19.665 100 76 152 3 2074135920 45494560 23279840
23:10:04 6048 8192000000 10985074464 11415560192 2017019104 19.757 100 62 125 0 2076607616 40526848 19061664
23:11:17 3683 8192000000 11027175568 11438354432 2031945888 19.874 100 82 164 4 2105867952 48334352 25587712
23:12:28 2417 8192000000 11031936304 11448332288 2033286928 19.884 100 94 188 0 2115655792 53384528 28984336
23:13:36 3135 8192000000 11001767072 11429228544 1981389872 19.476 98 77 152 2 2061936464 56457808 24088784
23:14:49 6293 8192000000 11007029360 11425427456 2017143488 19.758 100 73 146 0 2083979728 44499632 22336608
23:16:04 9898 8192000000 10985790576 11409539072 1985172464 19.506 99 62 124 1 2055464320 50311280 19980576
23:17:24 3583 8192000000 11014206944 11426672640 2012236688 19.719 100 75 150 2 2095538016 59175904 24125424
23:18:35 3121 8192000000 11010308976 11427647488 2000542960 19.627 100 70 141 2 2091745344 68755968 22446416
23:20:02 1361 8192000000 11029624656 11439542272 2008384384 19.689 100 92 184 1 2114049920 74720032 30945504
23:21:11 1944 8192000000 11010577344 11414171648 1991350896 19.554 100 90 176 2 2090857520 70053904 29452720
23:22:20 15436 8192000000 10980814944 11419508736 1979637712 19.462 100 55 111 2 2080357184 80909392 19810080
23:23:54 14783 8192000000 10964213520 11393318912 1943444768 19.174 98 46 92 1 2028659328 70191616 15022944
23:25:27 21250 8192000000 10962913280 11402059776 1959657152 19.303 100 39 78 1 2047522848 74033696 13832000
23:26:57 17750 8192000000 10956154304 11406721024 1939077216 19.139 99 32 64 1 2032732976 82067344 11588416
23:28:17 19941 8192000000 10945316560 11389497344 1897891168 18.809 98 34 68 2 2002305216 91105312 13308736
23:30:27 36438 8192000000 10936371504 11384500224 1953431920 19.254 100 19 36 2 2011091040 50968512 6690608
23:32:08 25243 8192000000 10905501632 11369754624 1946084752 19.195 100 23 46 2 1985913200 32791392 7037056
23:33:40 17443 8192000000 10884559840 11333881856 1936685168 19.120 99 47 95 2 1961171472 9883472 14602832
23:35:04 373661 8192000000 10854313248 11316609024 1755650848 17.648 90 20 41 2 1776061040 14798112 5612080
23:42:27 90408 8192000000 10855482080 11320434688 1905663120 18.872 98 10 20 0 1920202928 11611200 2928608
23:44:59 96307 8192000000 10897558800 11341275136 1949450144 19.222 100 28 56 0 1964301248 6356752 8494352
23:47:36 70650 8192000000 10895647488 11341168640 1907411856 18.886 97 28 56 0 1923071104 7991904 7667344
23:49:53 178949 8192000000 10863891904 11315191808 1885582176 18.710 96 27 53 1 1898411488 6029024 6800288
23:53:55 49204 8192000000 10897327536 11354259456 1965495408 19.350 100 26 51 2 1987586848 14661216 7430224
23:55:49 64614 8192000000 10893781344 11368583168 1973617792 19.414 100 25 50 2 2004648896 23428976 7602128
23:57:55 34179 8192000000 10875427264 11335024640 1955522400 19.270 99 20 40 2 1975440832 14289696 5628736
23:59:30 72275 8192000000 10898696960 11366846464 1980151184 19.466 100 20 40 1 1995767408 9280496 6335728
00:01:44 74156 8192000000 10843279296 11306299392 1863194752 18.529 94 25 51 0 1887955104 17419680 7340672
00:03:59 67858 8192000000 10843368400 11317006336 1919379344 18.982 99 15 29 1 1932541840 9016096 4146400
00:06:08 65657 8192000000 10855735744 11319590912 1923917792 19.018 99 17 34 1 1937385264 8025728 5441744
00:08:31 52535 8192000000 10877830608 11324047360 1896810592 18.801 97 31 62 1 1914918160 8934928 9172640
00:10:34 62767 8192000000 10854235472 11319078912 1904982752 18.866 98 21 42 1 1920283392 9396080 5904560
00:12:43 78067 8192000000 10887910944 11348140032 1922827488 19.009 98 19 38 1 1942329152 13880720 5620944
00:15:02 54888 8192000000 10847084288 11309707264 1880786144 18.671 96 17 32 2 1903872704 18436512 4650048
00:16:59 23507 8192000000 10862283376 11319939072 1916316960 18.957 99 30 60 1 1935371632 10914000 8140672
00:18:23 46069 8192000000 10871181376 11322576896 1867451968 18.564 96 36 72 1 1889967936 11591488 10924480
00:20:12 142080 8192000000 10869927440 11325124608 1874344224 18.619 96 21 42 1 1892663488 12938320 5380944
00:23:49 41622 8192000000 10873152016 11330031616 1946562752 19.199 100 23 46 0 1963581904 10775216 6243936
00:25:39 40399 8192000000 10853938416 11318669312 1898373024 18.813 97 28 56 0 1914211232 8479280 7358928
00:27:20 51371 8192000000 10859499232 11324383232 1912726256 18.929 98 8 16 1 1925105008 10338736 2040016
00:29:26 76631 8192000000 10845316512 11310358528 1807593088 18.076 93 9 18 1 1828923664 18763232 2567344
00:31:44 20428 8192000000 10879704352 11308867584 1880563552 18.670 97 37 71 1 1900134432 9807552 9763328
00:33:10 126289 8192000000 10859865408 11322703872 1857459776 18.483 95 16 32 0 1875804864 13515280 4829808
00:36:18 60897 8192000000 10903926048 11358330880 1940484256 19.151 99 26 52 0 1960631008 12008480 8138272
00:38:20 74245 8192000000 10887380944 11361890304 1943904704 19.178 99 8 16 2 1964627632 18243200 2479728
00:40:35 82345 8192000000 10892065056 11363586048 1926203680 19.037 98 8 16 0 1945642592 17056160 2382752
00:43:00 78009 8192000000 10880430512 11349938176 1931182976 19.076 98 23 46 0 1952303584 14805088 6315520
00:45:46 57697 8192000000 10875362800 11323928576 1934538288 19.103 99 33 64 5 1951475344 7346736 9590320
00:47:45 89242 8192000000 10882449376 11370348544 1948027904 19.211 99 11 22 0 1966649968 15282016 3340048
00:50:15 54914 8192000000 10845810928 11299749888 1890329680 18.748 96 18 36 1 1909445360 13321472 5794208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 22-08-2020 00:54:45 to 22-08-2020 00:54:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 87 v_add_invoice_to_stock 335544517
user_exc 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-08-2020 00:55:00 to 22-08-2020 00:55:02.

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          215012
MON$OLDEST_ACTIVE               219009
MON$OLDEST_SNAPSHOT             219009
MON$NEXT_TRANSACTION            219009
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                       2057440
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 ---
:141: 
:142: mscs-test-161.budzdorov.ru    Sat Aug 22 00:52:36 2020
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:144: 
:145: 
:146: mscs-test-161.budzdorov.ru    Sat Aug 22 00:52:36 2020
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:148: 
:149: 
:150: mscs-test-161.budzdorov.ru    Sat Aug 22 00:52:36 2020
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:152: 
:153: 
:154: mscs-test-161.budzdorov.ru    Sat Aug 22 00:52:36 2020
:155:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:156: 
--- end of diff output ---

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

22.08.20 00:55:04. 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-22 00:55:06 NO_SEVERE_ERRORS_FOUND
22.08.20 00:55:08. End of report.