Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.08.2020 12: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 16150944 kB
MemAvailable 30902140 kB
Buffers 0 kB
Cached 15465992 kB
SwapCached 6044 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-21 12:51:52.8620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(31:12) ==> SP_HALT_ON_ERROR(46:13)
IP                              ::1/35734
TRN_ID                          221457
ATT_ID                          497
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: 8292.08 29931 995050
customer order: creation 159.93 440 19191
customer order: refuse 13.71 491 1645
order to supplier: creation 41.94 1680 5033
order to supplier: removal 3.13 163376 375
invoice (draft): creation 42.55 4882 5106
invoice (draft): removal 47.43 1328 5692
invoice accept: apply 38.35 23923 4602
invoice accept: cancel 42.47 1321 5096
customer reserve: creation 3503.86 232 420463
customer reserve: removal 4134.40 94 496128
realization accept: apply 77.88 252 9346
realization accept: cancel 67.80 173 8136
payment from customer: creation 54.44 134 6533
payment from customer: removal 5.50 118 660
payment to supplier: creation 46.28 148 5554
payment to supplier: removal 3.18 114 382
service: total inventory turnovers 3.04 6761 365
service: total monetary turnovers 5.53 1194 663
service: aggregate perf. data 0.63 35389 76
service: refresh index statistics 0.03 356560 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-08-2020 12:52:35 to 21-08-2020 12:52:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3024
TEST_TIME 2 2888
TEST_TIME 3 3676
TEST_TIME 4 4136
TEST_TIME 5 3998
TEST_TIME 6 3876
TEST_TIME 7 4043
TEST_TIME 8 4209
TEST_TIME 9 4158
TEST_TIME 10 4330
TEST_TIME 11 4421
TEST_TIME 12 4567
TEST_TIME 13 4586
TEST_TIME 14 4754
TEST_TIME 15 4712
TEST_TIME 16 4743
TEST_TIME 17 4925
TEST_TIME 18 5119
TEST_TIME 19 5140
TEST_TIME 20 5299
TEST_TIME 21 5708
TEST_TIME 22 6077
TEST_TIME 23 6414
TEST_TIME 24 6523
TEST_TIME 25 6526
TEST_TIME 26 6891
TEST_TIME 27 6841
TEST_TIME 28 6762
TEST_TIME 29 6945
TEST_TIME 30 7123
TEST_TIME 31 7107
TEST_TIME 32 7273
TEST_TIME 33 7672
TEST_TIME 34 7939
TEST_TIME 35 7814
TEST_TIME 36 7850
TEST_TIME 37 8067
TEST_TIME 38 8007
TEST_TIME 39 7979
TEST_TIME 40 8436
TEST_TIME 41 8609
TEST_TIME 42 8559
TEST_TIME 43 8697
TEST_TIME 44 9154
TEST_TIME 45 9071
TEST_TIME 46 9087
TEST_TIME 47 8943
TEST_TIME 48 9016
TEST_TIME 49 8942
TEST_TIME 50 8919
TEST_TIME 51 8896
TEST_TIME 52 8783
TEST_TIME 53 8699
TEST_TIME 54 8644
TEST_TIME 55 8530
TEST_TIME 56 8469
TEST_TIME 57 8442
TEST_TIME 58 8377
TEST_TIME 59 8332
TEST_TIME 60 8349
TEST_TIME 61 8311
TEST_TIME 62 8419
TEST_TIME 63 8415
TEST_TIME 64 8419
TEST_TIME 65 8424
TEST_TIME 66 8392
TEST_TIME 67 8319
TEST_TIME 68 8366
TEST_TIME 69 8430
TEST_TIME 70 8417
TEST_TIME 71 8440
TEST_TIME 72 8447
TEST_TIME 73 8513
TEST_TIME 74 8477
TEST_TIME 75 8443
TEST_TIME 76 8510
TEST_TIME 77 8557
TEST_TIME 78 8576
TEST_TIME 79 8558
TEST_TIME 80 8575
TEST_TIME 81 8523
TEST_TIME 82 8480
TEST_TIME 83 8407
TEST_TIME 84 8378
TEST_TIME 85 8342
TEST_TIME 86 8322
TEST_TIME 87 8270
TEST_TIME 88 8260
TEST_TIME 89 8282
TEST_TIME 90 8237
TEST_TIME 91 8197
TEST_TIME 92 8177
TEST_TIME 93 8198
TEST_TIME 94 8202
TEST_TIME 95 8196
TEST_TIME 96 8239
TEST_TIME 97 8340
TEST_TIME 98 8330
TEST_TIME 99 8321
TEST_TIME 100 8340
TEST_TIME 101 8339
TEST_TIME 102 8425
TEST_TIME 103 8446
TEST_TIME 104 8460
TEST_TIME 105 8420
TEST_TIME 106 8347
TEST_TIME 107 8343
TEST_TIME 108 8353
TEST_TIME 109 8310
TEST_TIME 110 8287
TEST_TIME 111 8268
TEST_TIME 112 8255
TEST_TIME 113 8279
TEST_TIME 114 8255
TEST_TIME 115 8255
TEST_TIME 116 8297
TEST_TIME 117 8310
TEST_TIME 118 8343
TEST_TIME 119 8330
TEST_TIME 120 8299

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-08-2020 12:52:39 to 21-08-2020 12:52:42.

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 1383154 1383154 0 0.00 0 18880 9 0 0
doc_list_biud 1383151 1383151 0 0.00 0 38437 43 0 0
sp_add_invoice_to_stock 4711 4602 109 2.31 29 233167 23923 0 109
sp_cancel_adding_invoice 5096 5096 0 0.00 5 81729 1322 0 0
sp_cancel_client_order 1645 1645 0 0.00 4 16036 492 0 0
sp_cancel_customer_reserve 496128 496128 0 0.00 0 22981 95 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 2830 118 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 3752 114 0 0
sp_cancel_supplier_invoice 5700 5692 8 0.14 2 24623 1328 0 8
sp_cancel_supplier_order 375 375 0 0.00 5 1379516 163377 0 0
sp_cancel_write_off 8136 8136 0 0.00 1 12471 174 0 0
sp_client_order 19191 19191 0 0.00 3 18943 440 0 0
sp_customer_reserve 420463 420463 0 0.00 0 45091 233 0 0
sp_fill_shopping_cart 29303 29303 0 0.00 1 19732 59 0 0
sp_fill_shopping_cart_clo_res 420448 420448 0 0.00 0 17532 21 0 0
sp_get_clo_for_invoice 4587 4587 0 0.00 0 30718 485 0 0
sp_kill_cost_storno 14274 14274 0 0.00 0 7645 106 0 0
sp_kill_qstorno_ret_qs2qd 1006459 1006459 0 0.00 0 38436 51 0 0
sp_kill_qty_storno 531004 531004 0 0.00 0 38437 112 0 0
sp_lock_selected_doc 19162 19162 0 0.00 0 9504 4 0 0
sp_make_cost_storno 26019 26019 0 0.00 0 18859 80 0 0
sp_multiply_rows_for_pdistr 26019 26019 0 0.00 0 10552 43 0 0
sp_multiply_rows_for_qdistr 19191 19191 0 0.00 0 18587 222 0 0
sp_pay_from_customer 6533 6533 0 0.00 4 11479 135 0 0
sp_pay_to_supplier 5554 5554 0 0.00 4 11638 149 0 0
sp_payment_common 12086 12086 0 0.00 1 11465 21 0 0
sp_qd_handle_on_cancel_clo 1645 1645 0 0.00 0 9260 461 0 0
sp_qd_handle_on_invoice_upd_sts 9682 9682 0 0.00 0 19752 579 0 0
sp_qd_handle_on_reserve_upd_sts 9346 9346 0 0.00 0 16681 72 0 0
sp_reserve_write_off 9346 9346 0 0.00 6 18892 253 0 0
sp_supplier_invoice 5106 5106 0 0.00 6 96708 4882 0 0
sp_supplier_order 5033 5033 0 0.00 4 22826 1680 0 0
srv_aggregate_perf_data 76 76 0 0.00 2564 496551 35389 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 1593 116158 35246 0 0
srv_find_qd_qs_mism 950532 950532 0 0.00 0 11897 8 0 0
srv_make_invnt_saldo 365 365 0 0.00 65 152220 6762 0 0
srv_make_money_saldo 663 663 0 0.00 1 21519 1194 0 0
srv_recalc_idx_stat 4 4 0 0.00 125777 523067 356560 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4603 4603 0 0.00 0 13374 287 0 0
t$perf-norm:sp_cancel_adding_invoice 347 347 0 0.00 0 13185 155 0 0
t$perf-norm:sp_cancel_client_order 1645 1645 0 0.00 0 1789 21 0 0
t$perf-norm:sp_cancel_customer_reserve 616 616 0 0.00 0 1895 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 1104 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 10638 39 0 0
t$perf-norm:sp_cancel_supplier_invoice 357 357 0 0.00 0 1896 34 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 45766 3220 0 0
t$perf-norm:sp_cancel_write_off 849 849 0 0.00 0 631 6 0 0
t$perf-norm:sp_client_order 19191 19191 0 0.00 0 9925 29 0 0
t$perf-norm:sp_customer_reserve 901 901 0 0.00 0 1373 9 0 0
t$perf-norm:sp_pay_from_customer 6533 6533 0 0.00 0 8996 14 0 0
t$perf-norm:sp_pay_to_supplier 5554 5554 0 0.00 0 11761 14 0 0
t$perf-norm:sp_reserve_write_off 9346 9346 0 0.00 0 9974 15 0 0
t$perf-norm:sp_supplier_invoice 5106 5106 0 0.00 0 5387 34 0 0
t$perf-norm:sp_supplier_order 5033 5033 0 0.00 0 4680 27 0 0
t$perf-norm:srv_aggregate_perf_data 76 76 0 0.00 0 705 52 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 175 5 0 0
t$perf-norm:srv_make_invnt_saldo 365 365 0 0.00 0 797 20 0 0
t$perf-norm:srv_make_money_saldo 663 663 0 0.00 0 4757 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 6 0 0
v_add_invoice_to_stock 9387 9170 217 2.31 0 11466 21 0 217
v_all_customers 34744 34744 0 0.00 0 126 1 0 0
v_all_suppliers 28132 28132 0 0.00 0 127 1 0 0
v_all_wares 38380 38380 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 694 694 0 0.00 0 745 10 0 0
v_cancel_client_order 3290 3290 0 0.00 0 2635 5 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 2706 9 0 0
v_cancel_customer_reserve 1232 1232 0 0.00 0 1825 7 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 3740 10 0 0
v_cancel_supplier_invoice 730 714 16 2.19 0 1011 15 0 16
v_cancel_supplier_order 750 750 0 0.00 0 2500 13 0 0
v_cancel_write_off 1698 1698 0 0.00 0 6359 24 0 0
v_max_id_clo_ord 5021 5021 0 0.00 0 3630 3 0 0
v_max_id_clo_res 901 901 0 0.00 0 4612 24 0 0
v_max_id_ord_sup 5091 5091 0 0.00 0 8608 6 0 0
v_max_non_paid_invoice 1599 1599 0 0.00 0 134 2 0 0
v_max_non_paid_realizn 3522 3522 0 0.00 0 5581 5 0 0
v_min_id_clo_ord 5021 5021 0 0.00 0 2472 11 0 0
v_min_id_clo_res 901 901 0 0.00 0 2487 11 0 0
v_min_id_ord_sup 5091 5091 0 0.00 0 9846 11 0 0
v_min_non_paid_invoice 5553 5553 0 0.00 0 3880 11 0 0
v_min_non_paid_realizn 6533 6533 0 0.00 0 5513 13 0 0
v_our_firm 9658 9658 0 0.00 0 70 1 0 0
v_reserve_write_off 18692 18692 0 0.00 0 2650 6 0 0
x_lock_depdocs_on_canc_invoice 10431 10431 0 0.00 0 19898 178 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 26498 632 0 0
x_make_qstorno_1000_1200 5033 5033 0 0.00 1 22594 1553 0 0
x_make_qstorno_1200_2000 5106 5106 0 0.00 3 96693 4651 0 0
x_make_qstorno_2100_3300 420463 420463 0 0.00 0 45087 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-08-2020 12:52:43 to 21-08-2020 12:52:47.

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
10:21:56 2 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
10:22:58 25682 8192000000 9708681600 9998106624 784278512 8.737 100 89 178 0 814573664 7840512 22454640
10:24:27 16711 8192000000 9754180144 10054385664 829950048 9.199 100 82 164 0 860608864 10756352 19902464
10:25:46 21613 8192000000 9804355936 10114891776 867311344 9.573 100 84 168 1 906800960 20207104 19282512
10:27:12 14732 8192000000 9848482944 10177183744 909742448 9.995 100 75 150 2 955125520 28902720 16480352
10:28:30 11174 8192000000 9913480928 10252296192 956968992 10.459 100 86 172 0 1028083520 52545568 18568960
10:29:45 7475 8192000000 9962406624 10308079616 986282176 10.745 100 81 162 1 1071381472 68178720 16920576
10:30:54 4719 8192000000 9981274736 10337943552 1010452080 10.980 100 81 162 0 1096997088 70001504 16543504
10:32:02 3138 8192000000 9994967984 10357096448 1037193648 11.238 100 82 162 1 1107431056 53360080 16877328
10:33:10 1988 8192000000 10013235920 10378027008 1047163664 11.333 100 88 176 2 1124143392 59276368 17703360
10:34:13 36837 8192000000 9986738768 10358726656 1058234000 11.440 100 60 120 2 1107725488 36805552 12685936
10:35:51 6060 8192000000 9966047536 10357391360 1066263168 11.516 100 36 70 4 1091351328 16840528 8247632
10:36:59 3724 8192000000 9983483632 10369867776 1071463328 11.566 100 45 87 4 1092637808 11927632 9246848
10:38:03 8856 8192000000 9989498416 10384076800 1077129248 11.620 100 26 52 3 1098586224 16198960 5258016
10:39:15 55272 8192000000 10002642704 10408972288 1078503808 11.633 100 48 97 0 1103050768 14114576 10432384
10:41:12 4896 8192000000 10016462208 10408693760 1083404752 11.680 100 47 95 1 1123303344 29995616 9902976
10:42:18 8665 8192000000 9988633248 10389254144 1070403680 11.556 99 25 50 1 1097513328 21513584 5596064
10:43:27 9093 8192000000 9977203872 10377363456 1039463632 11.260 97 24 47 2 1065661680 20792432 5405616
10:44:36 53082 8192000000 9955658992 10344546304 1007613792 10.952 97 38 76 4 1044254944 28748160 7892992
10:46:33 6932 8192000000 9904481888 10279567360 945852080 10.350 93 33 66 1 969667072 15964192 7850800
10:47:41 8491 8192000000 9924495408 10306129920 1003754768 10.915 99 37 74 1 1035879136 24030640 8093728
10:48:50 5640 8192000000 9943281680 10320601088 1024632256 11.117 99 42 84 0 1046901712 12789984 9479472
10:49:58 44696 8192000000 9945711856 10351652864 1037755840 11.243 100 49 98 4 1074844400 26623984 10464576
10:51:44 6489 8192000000 9974397168 10366824448 1063717824 11.492 100 37 70 2 1090042752 18739760 7585168
10:53:00 17836 8192000000 10016611584 10409320448 1077829344 11.627 100 40 80 2 1110786624 24359504 8597776
10:54:26 7348 8192000000 10046097344 10433585152 1090502512 11.747 100 59 118 1 1148553744 45581232 12470000
10:55:38 12137 8192000000 10108791728 10493960192 1101952928 11.856 100 67 134 0 1213984848 98138848 13893072
10:56:54 44011 8192000000 10095398928 10499723264 1112895264 11.960 100 37 75 0 1228905552 108451728 7558560
10:58:42 15377 8192000000 10131236352 10533675008 1123941168 12.064 100 48 97 2 1228612976 94514992 10156816
11:00:05 17798 8192000000 10161187632 10561519616 1135255840 12.171 100 53 105 2 1273444352 126291680 11896832
11:01:28 12017 8192000000 10202802256 10602680320 1141179120 12.227 100 55 110 1 1300789504 147094512 12515872
11:03:00 4039 8192000000 10225577744 10626633728 1147387888 12.285 100 62 123 0 1330911904 169628128 13895888
11:04:14 6197 8192000000 10268287056 10662912000 1152981504 12.337 100 67 132 3 1364060864 195956864 15122496
11:05:31 10708 8192000000 10288079984 10700550144 1158761760 12.392 100 60 120 0 1401377888 229373872 13242256
11:06:55 7050 8192000000 10318331280 10722639872 1169973552 12.497 100 79 156 1 1424141136 235698016 18469568
11:08:11 4133 8192000000 10374035792 10772799488 1176772720 12.560 100 87 172 0 1469001472 272440160 19788592
11:09:22 5897 8192000000 10381004496 10789928960 1167717088 12.475 100 76 152 1 1483425424 298252512 17455824
11:10:45 5313 8192000000 10363283152 10779238400 1144407824 12.257 99 53 106 1 1475313808 318290208 12615776
11:11:56 11257 8192000000 10374939552 10811043840 1148380304 12.294 99 64 128 4 1480236224 316926864 14929056
11:13:14 2581 8192000000 10287923920 10725285888 1113084320 11.962 98 60 116 1 1382287136 255149376 14053440
11:14:21 8053 8192000000 10307731936 10737238016 1149085136 12.301 100 64 126 3 1408969584 244746816 15137632
11:15:39 3172 8192000000 10318658624 10713194496 1154587696 12.353 100 89 175 1 1410420864 236585520 19247648
11:16:48 1592 8192000000 10325270912 10717036544 1120610144 12.033 99 87 169 2 1416662944 276577504 19475296
11:17:57 6052 8192000000 10293512640 10706251776 1106616960 11.900 99 50 98 1 1387792288 270021056 11154272
11:19:12 4032 8192000000 10331981536 10727055360 1121633696 12.042 100 77 154 0 1430712096 291764528 17313872
11:20:27 2339 8192000000 10359255472 10782408704 1129421584 12.116 100 81 159 1 1458050080 309891440 18737056
11:21:44 4159 8192000000 10356950992 10745167872 1133379328 12.153 100 84 164 1 1458843744 306891440 18572976
11:22:56 2336 8192000000 10394808880 10801160192 1156510976 12.371 100 93 185 1 1485162176 307929984 20721216
11:24:11 3562 8192000000 10366748608 10790334464 1145999008 12.272 100 83 162 1 1462266480 297770944 18496528
11:25:21 2092 8192000000 10346390128 10746494976 1146520640 12.277 100 60 121 1 1440634992 280533248 13581104
11:26:27 8420 8192000000 10323775440 10735460352 1145416256 12.266 100 35 70 1 1417033040 263178416 8438368
11:27:41 7529 8192000000 10388034800 10804027392 1151981632 12.328 100 60 119 2 1485310320 319126480 14202208
11:28:54 21257 8192000000 10378119120 10826309632 1153939664 12.346 100 26 52 1 1478539408 317943152 6656592
11:30:44 18688 8192000000 10364859472 10860404736 1150977232 12.319 100 31 63 2 1510844672 351748176 8119264
11:32:07 31086 8192000000 10289539328 10795319296 1141219440 12.227 100 20 39 0 1427440496 281013024 5208032
11:33:59 5359 8192000000 10243323840 10691723264 1135251984 12.171 100 42 81 3 1374287728 228698208 10337536
11:35:13 7817 8192000000 10217133424 10694492160 1140121280 12.217 100 39 78 1 1324875664 176056976 8697408
11:36:24 55934 8192000000 10107855072 10528227328 1139254848 12.209 100 44 88 0 1207866496 58788768 9822880
11:38:24 64545 8192000000 10088410496 10556973056 1132506224 12.145 99 27 53 5 1196407376 57597664 6303488
11:40:40 87175 8192000000 10082924320 10545082368 1119771936 12.025 98 13 25 1 1198091792 75219264 3100592
11:43:08 85926 8192000000 10079273088 10509942784 1119004112 12.018 98 26 53 0 1155592512 31171232 5417168
11:45:38 62580 8192000000 10031498864 10458189824 1100614704 11.843 97 34 66 2 1128713536 21003072 7095760
11:47:44 51750 8192000000 10109890720 10570424320 1137886208 12.196 100 23 44 4 1223356752 80264768 5205776
11:49:37 25998 8192000000 10069635488 10509844480 1109006256 11.923 97 37 73 3 1166830736 50150576 7673904
11:51:06 33867 8192000000 10136533856 10582745088 1122477296 12.050 99 31 62 1 1227888048 98302896 7107856
11:52:48 12468 8192000000 10150610384 10587365376 1135972288 12.178 99 45 89 2 1248057168 102573744 9511136
11:54:06 57068 8192000000 10122095568 10626084864 1139590752 12.212 100 20 40 2 1256428496 111896416 4941328
11:56:09 56690 8192000000 10095592496 10571141120 1133797280 12.157 100 12 24 0 1210984560 74845440 2341840
11:58:16 74183 8192000000 10122587088 10648707072 1145321312 12.266 100 8 16 0 1214041088 67196336 1523440
12:00:32 23606 8192000000 10063389616 10482839552 1120812064 12.035 99 50 98 2 1153824416 21721024 11291328
12:01:57 44937 8192000000 10080255936 10520862720 1106338304 11.898 98 15 30 0 1153338864 43919312 3081248
12:03:49 34984 8192000000 10061969248 10493624320 1085795680 11.703 97 40 80 1 1154849872 60803664 8250528
12:05:28 26804 8192000000 10057056688 10475601920 1086016608 11.705 97 34 68 0 1118061104 25395264 6649232
12:06:56 62649 8192000000 10089141840 10563178496 1127161968 12.095 100 22 44 2 1217126496 85140112 4824416
12:09:11 116158 8192000000 10064459424 10523234304 1081879440 11.665 96 16 30 2 1144541504 59211920 3450144
12:12:12 62885 8192000000 10074235248 10561974272 1119851568 12.026 100 8 16 0 1192444080 70772832 1819680
12:14:17 60641 8192000000 10031484128 10490990592 1080688848 11.654 97 16 32 0 1112472352 28742320 3041184
12:16:20 40470 8192000000 10020604176 10466873344 1096329424 11.803 99 8 16 0 1127473456 29948320 1195712
12:18:12 54686 8192000000 10071489712 10509537280 1085887504 11.704 97 9 18 1 1135565440 47801648 1876288
12:20:07 45718 8192000000 10019323328 10471141376 1095339472 11.793 99 13 26 1 1128412432 30462112 2610848
12:21:57 40642 8192000000 10026945536 10467672064 1108068336 11.914 100 13 25 1 1138337072 27907376 2361360
12:23:42 78723 8192000000 10044265408 10514743296 1099688704 11.835 99 12 24 1 1168672528 66599216 2384608
12:26:09 51746 8192000000 10109923856 10551005184 1106901232 11.903 99 27 54 0 1185165168 72136128 6127808
12:28:20 88275 8192000000 10048105136 10491543552 1107573616 11.909 99 22 44 4 1163781616 52005120 4202880
12:30:52 52794 8192000000 10069697024 10507739136 1083523232 11.681 97 23 46 0 1126503872 38556608 4424032
12:32:51 73939 8192000000 10075381024 10570461184 1092626160 11.768 98 37 74 3 1195036320 94872240 7537920
12:35:06 66333 8192000000 10090196112 10535272448 1126668992 12.090 100 38 76 1 1195264240 61178496 7416752
12:37:15 107346 8192000000 10097133216 10573627392 1116253568 11.992 100 24 48 0 1198210336 77193712 4763056
12:40:03 50545 8192000000 10051000128 10562928640 1119054128 12.018 100 8 16 2 1172195136 51477776 1663232
12:41:53 68067 8192000000 10067063408 10520313856 1118865200 12.016 99 8 16 0 1163295872 43114832 1315840
12:44:02 91927 8192000000 10119468480 10591653888 1103838192 11.874 97 22 44 0 1172460688 64016368 4606128
12:46:45 29481 8192000000 10067598432 10521579520 1101909920 11.856 98 36 72 0 1172080688 62183840 7986928
12:48:17 46894 8192000000 10073904720 10532966400 1092515632 11.767 97 21 41 1 1149854912 53021712 4317568
12:50:27 57440 8192000000 10060157264 10476134400 1079103088 11.639 97 24 45 0 1116288992 32929680 4256224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-08-2020 12:52:49 to 21-08-2020 12:53:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 109 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-08-2020 12:53:04 to 21-08-2020 12:53:06.

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          221456
MON$OLDEST_ACTIVE               223991
MON$OLDEST_SNAPSHOT             223991
MON$NEXT_TRANSACTION            223991
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                       2114624
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             689
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2173 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2173 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2173 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.2173 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Fri Aug 21 12:51:57 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Fri Aug 21 12:52:01 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Fri Aug 21 12:52:01 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Fri Aug 21 12:52:02 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

21.08.20 12:53:08. 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-21 12:53:10 NO_SEVERE_ERRORS_FOUND
21.08.20 12:53:12. End of report.