Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.08.2020 06: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 16475452 kB
MemAvailable 30967944 kB
Buffers 0 kB
Cached 15203484 kB
SwapCached 5980 kB
Command: fdisk -l

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=fbc, val=/opt/fb40/bin
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=report_compress_cmd, val=/usr/bin/7za
param=host, val=localhost
param=port, val=3400
param=usr, val=SYSDBA
param=pwd, val=masterkey
param=tmpdir, val=/var/tmp/logs.oltp40
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/oltp40-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-19 06:51:50.1150
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/60598
TRN_ID                          216183
ATT_ID                          481
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: 8549.63 22944 1025955
customer order: creation 160.46 430 19255
customer order: refuse 13.52 526 1622
order to supplier: creation 42.60 1623 5112
order to supplier: removal 3.05 140250 366
invoice (draft): creation 43.07 4692 5168
invoice (draft): removal 47.61 1025 5713
invoice accept: apply 39.51 23402 4741
invoice accept: cancel 42.93 1089 5152
customer reserve: creation 3681.96 226 441835
customer reserve: removal 4203.06 79 504367
realization accept: apply 78.03 221 9364
realization accept: cancel 75.04 151 9005
payment from customer: creation 55.76 130 6691
payment from customer: removal 5.10 147 612
payment to supplier: creation 45.64 148 5477
payment to supplier: removal 3.03 99 364
service: total inventory turnovers 2.95 6173 354
service: total monetary turnovers 5.45 917 654
service: aggregate perf. data 0.83 20041 99
service: refresh index statistics 0.03 257514 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3833
TEST_TIME 2 3792
TEST_TIME 3 4252
TEST_TIME 4 4251
TEST_TIME 5 4361
TEST_TIME 6 4230
TEST_TIME 7 4235
TEST_TIME 8 4127
TEST_TIME 9 4238
TEST_TIME 10 4458
TEST_TIME 11 4685
TEST_TIME 12 4892
TEST_TIME 13 5137
TEST_TIME 14 5335
TEST_TIME 15 5579
TEST_TIME 16 5714
TEST_TIME 17 5850
TEST_TIME 18 6032
TEST_TIME 19 6221
TEST_TIME 20 6427
TEST_TIME 21 6752
TEST_TIME 22 6880
TEST_TIME 23 7017
TEST_TIME 24 7277
TEST_TIME 25 7309
TEST_TIME 26 7349
TEST_TIME 27 7538
TEST_TIME 28 7712
TEST_TIME 29 7721
TEST_TIME 30 7918
TEST_TIME 31 8077
TEST_TIME 32 8224
TEST_TIME 33 8417
TEST_TIME 34 8476
TEST_TIME 35 8729
TEST_TIME 36 8763
TEST_TIME 37 8797
TEST_TIME 38 9283
TEST_TIME 39 9389
TEST_TIME 40 9266
TEST_TIME 41 9405
TEST_TIME 42 9399
TEST_TIME 43 9379
TEST_TIME 44 9170
TEST_TIME 45 9074
TEST_TIME 46 9062
TEST_TIME 47 9019
TEST_TIME 48 8915
TEST_TIME 49 8944
TEST_TIME 50 8934
TEST_TIME 51 8960
TEST_TIME 52 8942
TEST_TIME 53 8986
TEST_TIME 54 9019
TEST_TIME 55 9017
TEST_TIME 56 9024
TEST_TIME 57 8958
TEST_TIME 58 8894
TEST_TIME 59 8893
TEST_TIME 60 8882
TEST_TIME 61 8812
TEST_TIME 62 8805
TEST_TIME 63 8753
TEST_TIME 64 8766
TEST_TIME 65 8761
TEST_TIME 66 8721
TEST_TIME 67 8657
TEST_TIME 68 8673
TEST_TIME 69 8777
TEST_TIME 70 8845
TEST_TIME 71 8895
TEST_TIME 72 8899
TEST_TIME 73 8881
TEST_TIME 74 8873
TEST_TIME 75 8896
TEST_TIME 76 8967
TEST_TIME 77 8960
TEST_TIME 78 8850
TEST_TIME 79 8820
TEST_TIME 80 8740
TEST_TIME 81 8674
TEST_TIME 82 8648
TEST_TIME 83 8605
TEST_TIME 84 8616
TEST_TIME 85 8669
TEST_TIME 86 8623
TEST_TIME 87 8661
TEST_TIME 88 8670
TEST_TIME 89 8705
TEST_TIME 90 8674
TEST_TIME 91 8704
TEST_TIME 92 8750
TEST_TIME 93 8786
TEST_TIME 94 8778
TEST_TIME 95 8796
TEST_TIME 96 8861
TEST_TIME 97 8796
TEST_TIME 98 8720
TEST_TIME 99 8686
TEST_TIME 100 8661
TEST_TIME 101 8600
TEST_TIME 102 8583
TEST_TIME 103 8549
TEST_TIME 104 8618
TEST_TIME 105 8660
TEST_TIME 106 8645
TEST_TIME 107 8638
TEST_TIME 108 8643
TEST_TIME 109 8692
TEST_TIME 110 8684
TEST_TIME 111 8721
TEST_TIME 112 8801
TEST_TIME 113 8789
TEST_TIME 114 8732
TEST_TIME 115 8695
TEST_TIME 116 8646
TEST_TIME 117 8628
TEST_TIME 118 8601
TEST_TIME 119 8596
TEST_TIME 120 8553

Performance per minute, chart


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

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1433287 1433287 0 0.00 0 21263 9 0 0
doc_list_biud 1433287 1433287 0 0.00 0 29247 35 0 0
sp_add_invoice_to_stock 4808 4741 67 1.39 43 211940 23402 0 67
sp_cancel_adding_invoice 5152 5152 0 0.00 4 98213 1090 0 0
sp_cancel_client_order 1622 1622 0 0.00 4 7834 527 0 0
sp_cancel_customer_reserve 504367 504367 0 0.00 0 29249 80 0 0
sp_cancel_pay_from_customer 612 612 0 0.00 2 7264 148 0 0
sp_cancel_pay_to_supplier 364 364 0 0.00 2 1946 100 0 0
sp_cancel_supplier_invoice 5718 5713 5 0.08 1 15078 1026 0 5
sp_cancel_supplier_order 366 366 0 0.00 4 1209846 140251 0 0
sp_cancel_write_off 9005 9005 0 0.00 1 6895 151 0 0
sp_client_order 19255 19255 0 0.00 3 28655 430 0 0
sp_customer_reserve 441835 441835 0 0.00 0 49836 226 0 0
sp_fill_shopping_cart 29506 29506 0 0.00 1 32023 47 0 0
sp_fill_shopping_cart_clo_res 441826 441826 0 0.00 0 25970 18 0 0
sp_get_clo_for_invoice 4732 4732 0 0.00 0 41218 442 0 0
sp_kill_cost_storno 15133 15133 0 0.00 0 7117 91 0 0
sp_kill_qstorno_ret_qs2qd 1023818 1023818 0 0.00 0 29246 43 0 0
sp_kill_qty_storno 540321 540321 0 0.00 0 29246 93 0 0
sp_lock_selected_doc 19401 19401 0 0.00 0 7504 4 0 0
sp_make_cost_storno 26264 26264 0 0.00 0 10564 67 0 0
sp_multiply_rows_for_pdistr 26264 26264 0 0.00 0 9559 42 0 0
sp_multiply_rows_for_qdistr 19255 19255 0 0.00 0 28569 240 0 0
sp_pay_from_customer 6691 6691 0 0.00 3 9662 130 0 0
sp_pay_to_supplier 5477 5477 0 0.00 4 13508 149 0 0
sp_payment_common 12168 12168 0 0.00 1 13495 22 0 0
sp_qd_handle_on_cancel_clo 1622 1622 0 0.00 0 7829 489 0 0
sp_qd_handle_on_invoice_upd_sts 9884 9884 0 0.00 0 28587 484 0 0
sp_qd_handle_on_reserve_upd_sts 9364 9364 0 0.00 0 10332 62 0 0
sp_reserve_write_off 9364 9364 0 0.00 5 18062 222 0 0
sp_supplier_invoice 5168 5168 0 0.00 6 90745 4692 0 0
sp_supplier_order 5112 5112 0 0.00 4 110808 1623 0 0
srv_aggregate_perf_data 99 99 0 0.00 2420 158910 20042 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 754 128738 40041 0 0
srv_find_qd_qs_mism 980251 980251 0 0.00 0 21263 7 0 0
srv_make_invnt_saldo 354 354 0 0.00 54 122162 6174 0 0
srv_make_money_saldo 654 654 0 0.00 1 21617 917 0 0
srv_recalc_idx_stat 4 4 0 0.00 56589 507762 257514 0 0
t$perf-abend:sp_add_invoice_to_stock 67 67 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 4741 4741 0 0.00 0 10056 278 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 4427 145 0 0
t$perf-norm:sp_cancel_client_order 1622 1622 0 0.00 0 4376 22 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 1323 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 612 612 0 0.00 0 911 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 364 364 0 0.00 0 1215 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 6964 56 0 0
t$perf-norm:sp_cancel_supplier_order 366 366 0 0.00 0 41814 2931 0 0
t$perf-norm:sp_cancel_write_off 913 913 0 0.00 0 4596 22 0 0
t$perf-norm:sp_client_order 19255 19255 0 0.00 0 12230 24 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 1876 9 0 0
t$perf-norm:sp_pay_from_customer 6691 6691 0 0.00 0 4818 12 0 0
t$perf-norm:sp_pay_to_supplier 5477 5477 0 0.00 0 6173 12 0 0
t$perf-norm:sp_reserve_write_off 9364 9364 0 0.00 0 11064 16 0 0
t$perf-norm:sp_supplier_invoice 5168 5168 0 0.00 0 8852 31 0 0
t$perf-norm:sp_supplier_order 5112 5112 0 0.00 0 5232 27 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 1356 48 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1417 51 0 0
t$perf-norm:srv_make_invnt_saldo 354 354 0 0.00 0 917 19 0 0
t$perf-norm:srv_make_money_saldo 654 654 0 0.00 0 4321 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 9596 9462 134 1.39 0 6485 19 0 134
v_all_customers 35432 35432 0 0.00 0 76 1 0 0
v_all_suppliers 28424 28424 0 0.00 0 20 1 0 0
v_all_wares 38488 38488 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 4478 14 0 0
v_cancel_client_order 3244 3244 0 0.00 0 3026 6 0 0
v_cancel_customer_prepayment 1224 1224 0 0.00 0 5266 16 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 1475 8 0 0
v_cancel_payment_to_supplier 728 728 0 0.00 0 1417 7 0 0
v_cancel_supplier_invoice 820 810 10 1.21 0 3635 27 0 10
v_cancel_supplier_order 732 732 0 0.00 0 1847 11 0 0
v_cancel_write_off 1826 1826 0 0.00 0 4128 23 0 0
v_max_id_clo_ord 5103 5103 0 0.00 0 640 2 0 0
v_max_id_clo_res 939 939 0 0.00 0 3272 24 0 0
v_max_id_ord_sup 5159 5159 0 0.00 0 4900 3 0 0
v_max_non_paid_invoice 1527 1527 0 0.00 0 445 3 0 0
v_max_non_paid_realizn 3475 3475 0 0.00 0 784 3 0 0
v_min_id_clo_ord 5103 5103 0 0.00 0 7914 9 0 0
v_min_id_clo_res 939 939 0 0.00 0 5315 10 0 0
v_min_id_ord_sup 5159 5159 0 0.00 0 8237 12 0 0
v_min_non_paid_invoice 5477 5477 0 0.00 0 13492 16 0 0
v_min_non_paid_realizn 6691 6691 0 0.00 0 3345 14 0 0
v_our_firm 9488 9488 0 0.00 0 14 1 0 0
v_reserve_write_off 18728 18728 0 0.00 0 5339 8 0 0
x_lock_depdocs_on_canc_invoice 10460 10460 0 0.00 0 25021 161 0 0
x_lock_depdocs_on_canc_sup_ord 366 366 0 0.00 0 5803 484 0 0
x_make_qstorno_1000_1200 5112 5112 0 0.00 1 110799 1510 0 0
x_make_qstorno_1200_2000 5168 5168 0 0.00 3 90192 4520 0 0
x_make_qstorno_2100_3300 441835 441835 0 0.00 0 49834 203 0 0

Completed "Performance in DETAILS". Done for 00:00:03, from 19-08-2020 06:53:23 to 19-08-2020 06:53:26.

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
04:21:53 6 8192000000 8796611856 8807043072 3144672 0.038 1 1 2 0 3241968 61344 35952
04:22:54 44483 8192000000 9719864320 10014007296 790676624 8.802 100 84 168 1 819036048 7450272 20909152
04:24:46 22257 8192000000 9757674704 10060509184 833981440 9.239 100 89 178 0 867339280 11868656 21489184
04:26:18 13858 8192000000 9793196672 10104418304 859567104 9.496 100 79 158 0 898551440 20446224 18538112
04:27:40 14563 8192000000 9848532400 10175967232 909233456 9.990 100 79 158 2 959342112 33043616 17065040
04:28:57 12281 8192000000 9902341920 10242547712 938864656 10.282 100 74 148 1 1012929584 58568160 15496768
04:30:14 6496 8192000000 9948361216 10291847168 972514080 10.611 100 86 172 0 1056733616 66740784 17478752
04:31:24 3485 8192000000 9975798016 10330046464 995467552 10.835 100 81 162 1 1096106976 84175424 16464000
04:32:30 2956 8192000000 9980081568 10339168256 1021411072 11.086 100 93 185 0 1089624000 49926752 18286176
04:33:35 3291 8192000000 10028872992 10396626944 1039172208 11.257 100 90 180 0 1144359840 86593376 18594256
04:34:40 1901 8192000000 10041752432 10406903808 1050791936 11.368 100 85 167 0 1152312928 84819952 16701040
04:35:44 1722 8192000000 10023042048 10392170496 1057685504 11.434 100 82 160 1 1134479632 60697200 16096928
04:36:48 9609 8192000000 9970808576 10353233920 1058590912 11.443 100 47 92 3 1088230624 19787280 9852432
04:38:01 5653 8192000000 9979441264 10368479232 1062021168 11.476 100 40 78 1 1103001040 32710912 8268960
04:39:08 5478 8192000000 9993543232 10381406208 1074835760 11.598 100 40 79 2 1102574432 18486192 9252480
04:40:15 45666 8192000000 10017869024 10406658048 1086476736 11.709 100 52 103 1 1120486048 22945472 11063840
04:42:01 5479 8192000000 10030271872 10429202432 1090263440 11.745 100 58 115 5 1127601056 24925088 12412528
04:43:08 5911 8192000000 9985419344 10387165184 1087823392 11.722 100 22 41 2 1109634416 17188768 4622256
04:44:19 9871 8192000000 9986094000 10387140608 1064098176 11.496 99 29 58 4 1093907968 23139232 6670560
04:45:32 18941 8192000000 9981898064 10361778176 1041420912 11.278 97 59 118 2 1067206800 12628032 13157856
04:46:51 5116 8192000000 9970847728 10351386624 1033733936 11.204 99 45 90 0 1079425136 35837920 9853280
04:47:58 8237 8192000000 9939622960 10331275264 1014527968 11.019 99 45 88 0 1048749520 25140144 9081408
04:49:11 8150 8192000000 9954477120 10347773952 1037415568 11.240 100 32 64 3 1071160160 26381536 7363056
04:50:21 24741 8192000000 9958795776 10363543552 1003796624 10.915 96 19 38 0 1027461216 20060560 3604032
04:51:46 10190 8192000000 9988376672 10367078400 1066716016 11.521 100 44 88 2 1096420192 19680848 10023328
04:53:11 50348 8192000000 10054576000 10443522048 1078252352 11.631 100 54 108 1 1150352400 60901136 11198912
04:55:39 15635 8192000000 10058119296 10454810624 1098834832 11.827 100 51 101 1 1165675472 55776752 11063888
04:57:01 41460 8192000000 10089715920 10494947328 1109824384 11.931 100 50 100 2 1193710688 72732224 11154080
04:58:51 45058 8192000000 10143460592 10549121024 1121055504 12.037 100 43 85 3 1251766880 121060288 9651088
05:00:40 11949 8192000000 10162274624 10566197248 1130964544 12.130 100 47 94 1 1270343712 129010992 10368176
05:02:04 10138 8192000000 10179440624 10591178752 1139398000 12.210 100 54 106 2 1283504048 132232320 11873728
05:03:18 2755 8192000000 10228930400 10624978944 1146203120 12.274 100 83 164 1 1325165888 160806256 18156512
05:04:27 13112 8192000000 10194790448 10603384832 1152872800 12.336 100 63 126 1 1304822800 138555472 13394528
05:05:42 1327 8192000000 10257620320 10652672000 1160802176 12.411 100 89 177 2 1356015808 175357280 19856352
05:06:49 6594 8192000000 10292794704 10700713984 1169547568 12.493 100 63 126 1 1397030112 213429136 14053408
05:08:00 6141 8192000000 10264569760 10698477568 1171684992 12.513 100 52 104 0 1375090320 191188416 12216912
05:09:14 1497 8192000000 10296457488 10703007744 1182649824 12.615 100 77 155 1 1393983520 193709744 17623952
05:10:24 4623 8192000000 10329379776 10734866432 1171522592 12.511 100 76 152 3 1425717424 236898592 17296240
05:11:37 1855 8192000000 10343946224 10749194240 1176637040 12.559 100 84 166 0 1436505120 241017264 18850816
05:12:48 6081 8192000000 10314091072 10736373760 1140681504 12.222 99 91 179 1 1409367968 248360112 20326352
05:14:03 3126 8192000000 10305752864 10710212608 1140991888 12.225 99 81 157 2 1396135296 237427600 17715808
05:15:17 2867 8192000000 10310169280 10706829312 1162713280 12.429 100 90 177 1 1406181392 223279472 20188640
05:16:25 754 8192000000 10351949536 10756063232 1163954336 12.440 100 94 184 1 1444296720 258833760 21508624
05:17:29 6146 8192000000 10370231120 10770857984 1155409600 12.360 100 79 160 3 1463396656 288666624 19320432
05:18:41 7299 8192000000 10375562960 10808360960 1156936240 12.375 100 50 100 0 1495780368 326486064 12358064
05:20:03 2323 8192000000 10373143216 10770960384 1162016144 12.422 100 89 178 2 1466636128 284293968 20326016
05:21:08 1963 8192000000 10404949424 10799730688 1159080640 12.395 100 86 165 1 1491270640 312349200 19840800
05:22:17 10718 8192000000 10341888832 10778517504 1148239088 12.293 99 47 94 0 1444393008 284475936 11677984
05:23:36 12607 8192000000 10377251616 10796515328 1161645696 12.419 100 48 95 1 1468835200 296055248 11134256
05:24:55 5599 8192000000 10339317232 10770903040 1166433520 12.463 100 36 72 0 1436501488 261416272 8651696
05:26:08 28448 8192000000 10281581632 10729021440 1131260992 12.133 99 22 43 3 1387284608 250709472 5314144
05:27:39 14650 8192000000 10251453408 10726920192 1106839008 11.902 98 19 38 1 1343279328 231592352 4847968
05:29:32 14309 8192000000 10141739152 10605989888 1128504400 12.107 100 47 95 1 1275156944 136301680 10350864
05:30:56 20645 8192000000 10119710464 10525798400 1126583728 12.089 99 55 108 3 1202146896 64091136 11472032
05:32:21 41598 8192000000 10086314048 10532315136 1122500736 12.051 99 28 56 0 1185026592 56854288 5671568
05:34:05 120683 8192000000 10053975568 10479005696 1099476064 11.833 97 32 64 2 1121601536 14920320 7205152
05:37:08 68871 8192000000 10078973888 10562760704 1120663056 12.033 99 18 36 0 1226442704 101780576 3999072
05:39:33 12153 8192000000 10100340832 10523815936 1111035120 11.942 98 43 84 3 1184338816 64101152 9202544
05:40:59 16463 8192000000 10102295184 10536050688 1119099344 12.018 99 37 73 2 1204992736 78722592 7170800
05:42:28 20727 8192000000 10120684736 10541277184 1109250592 11.925 98 52 105 1 1196061984 74805040 12006352
05:43:52 22211 8192000000 10144266640 10626166784 1135119792 12.170 100 25 49 1 1256652960 116307440 5225728
05:45:25 49289 8192000000 10103684816 10586992640 1133752576 12.157 100 9 17 0 1216589392 80901520 1935296
05:47:26 23686 8192000000 10050751520 10518458368 1118882592 12.016 100 11 21 0 1159225392 38400400 1942400
05:48:54 30821 8192000000 10081490848 10525814784 1126010224 12.084 100 14 28 0 1178315968 49090560 3215184
05:50:33 114124 8192000000 10086522192 10595336192 1133398672 12.153 100 16 32 0 1184624752 48011872 3214208
05:53:29 60826 8192000000 10071696992 10526060544 1133005696 12.150 99 15 30 0 1186092624 50057840 3029088
05:55:31 48583 8192000000 10048773408 10521436160 1094226032 11.783 97 9 18 1 1160727392 64451088 2050272
05:57:20 33632 8192000000 10059118304 10503237632 1101961136 11.856 98 36 70 1 1135643696 25955056 7727504
05:58:56 80086 8192000000 10128354048 10641014784 1123583344 12.061 99 31 62 2 1274239312 143270048 7385920
06:01:39 113049 8192000000 10031929104 10500669440 1069084704 11.543 95 14 28 1 1113759552 42099200 2575648
06:04:33 28713 8192000000 10085170048 10507120640 1089160160 11.735 97 52 104 0 1156938800 57302560 10476080
06:06:05 21102 8192000000 10126041008 10546425856 1119595488 12.023 100 51 103 4 1212171296 81970656 10605152
06:07:28 96958 8192000000 10076541424 10563776512 1092551344 11.767 97 16 32 1 1144802816 49234816 3016656
06:10:33 74090 8192000000 10023344528 10481123328 1080097216 11.648 97 10 20 0 1120707728 38636880 1973632
06:13:28 73347 8192000000 10050876000 10494623744 1082066160 11.667 98 11 22 1 1101565136 17269632 2229344
06:15:46 83246 8192000000 10048871664 10531897344 1085987520 11.704 97 9 18 0 1137863008 50082960 1792528
06:18:11 70013 8192000000 10070093520 10527457280 1101025264 11.847 98 14 28 0 1145797472 42037712 2734496
06:20:30 80825 8192000000 10097010576 10546012160 1090926720 11.751 96 15 30 2 1146924608 52843600 3154288
06:22:59 111961 8192000000 10079184048 10569736192 1071065552 11.562 95 23 46 1 1105413936 30282400 4065984
06:25:52 27191 8192000000 10081339040 10513178624 1121417744 12.040 99 24 48 2 1182487984 55908800 5161440
06:27:33 128738 8192000000 10069097520 10531225600 1112615344 11.957 98 17 34 0 1156744016 40141456 3987216
06:30:52 55251 8192000000 10070930800 10542067712 1138499728 12.201 100 9 18 1 1182396704 42074496 1822480
06:32:51 17233 8192000000 10088213488 10550091776 1144732368 12.260 100 8 16 0 1185512720 39024576 1755776
06:34:11 87571 8192000000 10182766896 10682650624 1134047744 12.160 99 14 27 0 1239476320 102106192 3322384
06:36:43 110813 8192000000 10090215616 10541404160 1104642144 11.882 96 10 21 0 1157261456 50324256 2295056
06:39:39 77748 8192000000 10117305040 10595274752 1135515056 12.173 99 12 24 2 1200881872 62593376 2773440
06:42:06 62312 8192000000 10149101008 10621001728 1129122224 12.113 98 27 54 1 1201263312 67031200 5109888
06:44:08 113362 8192000000 10072748864 10556985344 1120646624 12.033 98 22 44 1 1163029952 37694448 4688880
06:47:32 48143 8192000000 10091699904 10568855552 1143843904 12.252 100 5 10 0 1187097008 42354848 898256
06:49:22 63080 8192000000 10052959600 10519564288 1116326464 11.992 98 15 30 1 1176084464 56791968 2966032
06:51:25 33930 8192000000 9122558496 9389768704 275842656 3.257 24 9 18 1 299729904 21854528 2032720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-08-2020 06:53:28 to 19-08-2020 06:53:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 67 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 19-08-2020 06:53:43 to 19-08-2020 06:53:45.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          216838
MON$OLDEST_ACTIVE               216839
MON$OLDEST_SNAPSHOT             216839
MON$NEXT_TRANSACTION            216839
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-30 07:30:08.6030 GMT
MON$PAGES                       2086368
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000003c06020000000000
MON$NEXT_ATTACHMENT             672
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2164 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2164 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2164 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2164 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Wed Aug 19 06:52:03 2020
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs.oltp40 according to config parameter 'remove_isql_logs'

19.08.20 06:53:47. There are 3 logs in /var/tmp/logs.oltp40 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-19 06:53:49 NO_SEVERE_ERRORS_FOUND
19.08.20 06:53:52. End of report.