Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.08.2020 03: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 16160888 kB
MemAvailable 30930520 kB
Buffers 0 kB
Cached 15457924 kB
SwapCached 6012 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-20 03:51:53.2480
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/43914
TRN_ID                          217626
ATT_ID                          535
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: 8344.66 27542 1001359
customer order: creation 150.83 381 18100
customer order: refuse 13.24 455 1589
order to supplier: creation 40.75 1793 4890
order to supplier: removal 3.03 168395 363
invoice (draft): creation 40.58 5213 4870
invoice (draft): removal 46.40 1339 5568
invoice accept: apply 37.75 24318 4530
invoice accept: cancel 42.19 1417 5063
customer reserve: creation 3533.68 230 424041
customer reserve: removal 4171.06 93 500527
realization accept: apply 74.09 262 8891
realization accept: cancel 78.58 188 9429
payment from customer: creation 52.33 158 6280
payment from customer: removal 5.00 134 600
payment to supplier: creation 43.14 171 5177
payment to supplier: removal 2.87 109 344
service: total inventory turnovers 2.80 7257 336
service: total monetary turnovers 5.41 1151 649
service: aggregate perf. data 0.90 18029 108
service: refresh index statistics 0.03 319748 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-08-2020 03:54:44 to 20-08-2020 03:54:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4342
TEST_TIME 2 4097
TEST_TIME 3 3857
TEST_TIME 4 3624
TEST_TIME 5 3731
TEST_TIME 6 3865
TEST_TIME 7 3580
TEST_TIME 8 3926
TEST_TIME 9 4103
TEST_TIME 10 4184
TEST_TIME 11 4232
TEST_TIME 12 4592
TEST_TIME 13 4617
TEST_TIME 14 4665
TEST_TIME 15 4765
TEST_TIME 16 5339
TEST_TIME 17 5415
TEST_TIME 18 5672
TEST_TIME 19 5600
TEST_TIME 20 5707
TEST_TIME 21 5979
TEST_TIME 22 6119
TEST_TIME 23 6094
TEST_TIME 24 6108
TEST_TIME 25 6111
TEST_TIME 26 6108
TEST_TIME 27 6121
TEST_TIME 28 6175
TEST_TIME 29 6153
TEST_TIME 30 6852
TEST_TIME 31 7058
TEST_TIME 32 7246
TEST_TIME 33 7267
TEST_TIME 34 7312
TEST_TIME 35 7395
TEST_TIME 36 7328
TEST_TIME 37 7702
TEST_TIME 38 7964
TEST_TIME 39 8108
TEST_TIME 40 8060
TEST_TIME 41 8631
TEST_TIME 42 8869
TEST_TIME 43 8677
TEST_TIME 44 8628
TEST_TIME 45 8908
TEST_TIME 46 9008
TEST_TIME 47 8905
TEST_TIME 48 8908
TEST_TIME 49 8812
TEST_TIME 50 8760
TEST_TIME 51 8855
TEST_TIME 52 8778
TEST_TIME 53 8674
TEST_TIME 54 8711
TEST_TIME 55 8605
TEST_TIME 56 8628
TEST_TIME 57 8511
TEST_TIME 58 8537
TEST_TIME 59 8456
TEST_TIME 60 8399
TEST_TIME 61 8321
TEST_TIME 62 8286
TEST_TIME 63 8454
TEST_TIME 64 8380
TEST_TIME 65 8311
TEST_TIME 66 8258
TEST_TIME 67 8320
TEST_TIME 68 8276
TEST_TIME 69 8359
TEST_TIME 70 8302
TEST_TIME 71 8277
TEST_TIME 72 8386
TEST_TIME 73 8378
TEST_TIME 74 8405
TEST_TIME 75 8390
TEST_TIME 76 8454
TEST_TIME 77 8473
TEST_TIME 78 8449
TEST_TIME 79 8429
TEST_TIME 80 8329
TEST_TIME 81 8277
TEST_TIME 82 8264
TEST_TIME 83 8195
TEST_TIME 84 8172
TEST_TIME 85 8149
TEST_TIME 86 8097
TEST_TIME 87 8167
TEST_TIME 88 8154
TEST_TIME 89 8146
TEST_TIME 90 8203
TEST_TIME 91 8202
TEST_TIME 92 8219
TEST_TIME 93 8200
TEST_TIME 94 8263
TEST_TIME 95 8279
TEST_TIME 96 8272
TEST_TIME 97 8372
TEST_TIME 98 8354
TEST_TIME 99 8385
TEST_TIME 100 8408
TEST_TIME 101 8375
TEST_TIME 102 8323
TEST_TIME 103 8294
TEST_TIME 104 8251
TEST_TIME 105 8218
TEST_TIME 106 8204
TEST_TIME 107 8232
TEST_TIME 108 8225
TEST_TIME 109 8210
TEST_TIME 110 8205
TEST_TIME 111 8182
TEST_TIME 112 8212
TEST_TIME 113 8274
TEST_TIME 114 8290
TEST_TIME 115 8417
TEST_TIME 116 8417
TEST_TIME 117 8426
TEST_TIME 118 8416
TEST_TIME 119 8401
TEST_TIME 120 8351

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-08-2020 03:54:48 to 20-08-2020 03:54:51.

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 1391610 1391610 0 0.00 0 18119 9 0 0
doc_list_biud 1391610 1391610 0 0.00 0 51264 43 0 0
sp_add_invoice_to_stock 4620 4530 90 1.94 45 209488 24319 0 90
sp_cancel_adding_invoice 5063 5063 0 0.00 4 78133 1417 0 0
sp_cancel_client_order 1589 1589 0 0.00 4 14772 456 0 0
sp_cancel_customer_reserve 500527 500527 0 0.00 1 22997 94 0 0
sp_cancel_pay_from_customer 600 600 0 0.00 2 4843 135 0 0
sp_cancel_pay_to_supplier 344 344 0 0.00 2 7341 110 0 0
sp_cancel_supplier_invoice 5575 5568 7 0.12 2 51272 1340 0 7
sp_cancel_supplier_order 363 363 0 0.00 5 1361339 168396 0 0
sp_cancel_write_off 9429 9429 0 0.00 1 9226 189 0 0
sp_client_order 18100 18100 0 0.00 3 44984 381 0 0
sp_customer_reserve 424041 424041 0 0.00 0 73643 230 0 0
sp_fill_shopping_cart 27848 27848 0 0.00 1 27929 65 0 0
sp_fill_shopping_cart_clo_res 424030 424030 0 0.00 0 16584 21 0 0
sp_get_clo_for_invoice 4519 4519 0 0.00 0 38975 509 0 0
sp_kill_cost_storno 15436 15436 0 0.00 0 7926 110 0 0
sp_kill_qstorno_ret_qs2qd 1016414 1016414 0 0.00 0 51262 51 0 0
sp_kill_qty_storno 535948 535948 0 0.00 0 51263 110 0 0
sp_lock_selected_doc 18568 18568 0 0.00 0 10643 5 0 0
sp_make_cost_storno 24866 24866 0 0.00 0 17382 85 0 0
sp_multiply_rows_for_pdistr 24866 24866 0 0.00 0 12980 49 0 0
sp_multiply_rows_for_qdistr 18100 18100 0 0.00 0 17498 183 0 0
sp_pay_from_customer 6280 6280 0 0.00 3 16807 158 0 0
sp_pay_to_supplier 5177 5177 0 0.00 5 16630 171 0 0
sp_payment_common 11457 11457 0 0.00 1 15327 35 0 0
sp_qd_handle_on_cancel_clo 1589 1589 0 0.00 0 14765 427 0 0
sp_qd_handle_on_invoice_upd_sts 9581 9581 0 0.00 0 40251 581 0 0
sp_qd_handle_on_reserve_upd_sts 8891 8891 0 0.00 0 22796 75 0 0
sp_reserve_write_off 8891 8891 0 0.00 6 22818 262 0 0
sp_supplier_invoice 4870 4870 0 0.00 6 118859 5213 0 0
sp_supplier_order 4890 4890 0 0.00 5 55271 1793 0 0
srv_aggregate_perf_data 108 108 0 0.00 2744 177820 18030 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 1450 126250 35969 0 0
srv_find_qd_qs_mism 957585 957585 0 0.00 0 11538 7 0 0
srv_make_invnt_saldo 336 336 0 0.00 38 119106 7257 0 0
srv_make_money_saldo 649 649 0 0.00 1 20743 1152 0 0
srv_recalc_idx_stat 4 4 0 0.00 63983 497278 319749 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4531 4531 0 0.00 0 10803 298 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 4012 196 0 0
t$perf-norm:sp_cancel_client_order 1589 1589 0 0.00 0 2234 20 0 0
t$perf-norm:sp_cancel_customer_reserve 625 625 0 0.00 0 1165 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 600 600 0 0.00 0 3593 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 344 344 0 0.00 0 337 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1291 28 0 0
t$perf-norm:sp_cancel_supplier_order 363 363 0 0.00 0 38558 3311 0 0
t$perf-norm:sp_cancel_write_off 878 878 0 0.00 0 3040 15 0 0
t$perf-norm:sp_client_order 18100 18100 0 0.00 0 9663 26 0 0
t$perf-norm:sp_customer_reserve 945 945 0 0.00 0 2294 12 0 0
t$perf-norm:sp_pay_from_customer 6280 6280 0 0.00 0 8574 16 0 0
t$perf-norm:sp_pay_to_supplier 5177 5177 0 0.00 0 2258 12 0 0
t$perf-norm:sp_reserve_write_off 8891 8891 0 0.00 0 8613 16 0 0
t$perf-norm:sp_supplier_invoice 4870 4870 0 0.00 0 4732 33 0 0
t$perf-norm:sp_supplier_order 4890 4890 0 0.00 0 2818 30 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 530 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 865 23 0 0
t$perf-norm:srv_make_invnt_saldo 336 336 0 0.00 0 1015 19 0 0
t$perf-norm:srv_make_money_saldo 649 649 0 0.00 0 4395 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 5 0 0
v_add_invoice_to_stock 9216 9036 180 1.95 0 10269 23 0 180
v_all_customers 32992 32992 0 0.00 0 16 1 0 0
v_all_suppliers 27104 27104 0 0.00 0 118 1 0 0
v_all_wares 36200 36200 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 1437 11 0 0
v_cancel_client_order 3178 3178 0 0.00 0 2190 6 0 0
v_cancel_customer_prepayment 1200 1200 0 0.00 0 1010 7 0 0
v_cancel_customer_reserve 1250 1250 0 0.00 0 770 4 0 0
v_cancel_payment_to_supplier 688 688 0 0.00 0 787 7 0 0
v_cancel_supplier_invoice 764 750 14 1.83 0 7163 32 0 14
v_cancel_supplier_order 726 726 0 0.00 0 1059 8 0 0
v_cancel_write_off 1756 1756 0 0.00 0 8044 22 0 0
v_max_id_clo_ord 4888 4888 0 0.00 0 11040 5 0 0
v_max_id_clo_res 945 945 0 0.00 0 1170 10 0 0
v_max_id_ord_sup 4860 4860 0 0.00 0 5554 6 0 0
v_max_non_paid_invoice 1373 1373 0 0.00 0 167 2 0 0
v_max_non_paid_realizn 3443 3443 0 0.00 0 5943 7 0 0
v_min_id_clo_ord 4888 4888 0 0.00 0 11430 28 0 0
v_min_id_clo_res 945 945 0 0.00 0 6803 15 0 0
v_min_id_ord_sup 4860 4860 0 0.00 0 7667 8 0 0
v_min_non_paid_invoice 5177 5177 0 0.00 0 12705 19 0 0
v_min_non_paid_realizn 6280 6280 0 0.00 0 12794 28 0 0
v_our_firm 8882 8882 0 0.00 0 28 1 0 0
v_reserve_write_off 17782 17782 0 0.00 0 8344 5 0 0
x_lock_depdocs_on_canc_invoice 10256 10256 0 0.00 0 27651 203 0 0
x_lock_depdocs_on_canc_sup_ord 363 363 0 0.00 0 7546 573 0 0
x_make_qstorno_1000_1200 4890 4890 0 0.00 1 55140 1646 0 0
x_make_qstorno_1200_2000 4870 4870 0 0.00 3 118620 4973 0 0
x_make_qstorno_2100_3300 424041 424041 0 0.00 0 73639 204 0 0

Completed "Performance in DETAILS". Done for 00:00:03, from 20-08-2020 03:54:52 to 20-08-2020 03:54:55.

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
01:21:56 1 8192000000 8796611856 8807043072 3144672 0.038 1 1 2 0 3241968 61344 35952
01:22:57 24765 8192000000 9731547776 10025029632 808839440 8.986 100 90 180 2 837029632 7025648 21164544
01:24:30 28478 8192000000 9767249328 10071265280 839404736 9.294 100 91 182 0 873134000 12478368 21250896
01:26:01 17829 8192000000 9804651680 10116833280 869222400 9.592 100 82 164 0 910786704 22667808 18896496
01:27:23 15055 8192000000 9858233568 10184396800 911085728 10.008 100 82 164 5 972417424 43086704 18244992
01:28:52 13257 8192000000 9909129008 10251755520 950308416 10.394 100 78 156 1 1030095504 63161872 16625216
01:30:07 8362 8192000000 9942005280 10292887552 976202624 10.647 100 75 150 0 1050077840 58412928 15462288
01:31:19 4711 8192000000 9980546080 10340098048 1005998304 10.937 100 77 154 2 1094683328 72594464 16090560
01:32:26 2464 8192000000 10011311776 10372825088 1032953936 11.197 100 81 162 0 1122919376 73412352 16553088
01:33:31 1946 8192000000 10040083456 10407075840 1047343360 11.335 100 89 178 1 1153004688 86946192 18715136
01:34:36 1505 8192000000 10040635232 10413236224 1060094528 11.457 100 89 178 0 1159360304 81234096 18031680
01:35:39 9931 8192000000 10001383888 10379554816 1067731680 11.530 100 69 137 2 1122904656 41528384 13644592
01:36:51 3959 8192000000 9999944400 10401501184 1070949232 11.561 100 41 80 2 1122283600 42582656 8751712
01:37:58 7148 8192000000 9995436912 10393985024 1076556816 11.615 100 30 59 3 1110461776 27813952 6091008
01:39:08 5583 8192000000 10013132496 10395881472 1083271296 11.679 100 36 72 2 1120599488 29799856 7528336
01:40:15 36141 8192000000 10013199392 10398334976 1083055440 11.677 100 53 106 1 1103379840 7144896 13179504
01:41:52 6888 8192000000 10015292048 10419654656 1087693616 11.721 100 26 52 2 1107852272 14325248 5833408
01:43:01 8552 8192000000 10000551568 10397429760 1056248560 11.421 98 29 59 2 1104619456 42012768 6358128
01:44:10 7922 8192000000 9963993520 10355716096 1021662944 11.088 97 24 47 3 1051466976 24724768 5079264
01:45:19 23478 8192000000 9933540544 10332270592 987693376 10.759 94 13 26 0 1014295504 24337568 2264560
01:46:48 9756 8192000000 9919550992 10305630208 983182960 10.715 97 34 67 2 1016318208 25297936 7837312
01:47:58 8820 8192000000 9940417104 10327179264 1001951280 10.897 98 36 72 1 1026950368 16219184 8779904
01:49:08 70936 8192000000 9948139968 10352033792 1043357072 11.297 100 23 46 2 1069732368 21942432 4432864
01:51:19 8703 8192000000 9981793648 10377846784 1064027696 11.495 100 47 93 1 1102828928 28547888 10253344
01:52:30 7668 8192000000 10014998160 10403999744 1080456320 11.652 100 51 100 1 1118712688 28032368 10224000
01:53:52 6594 8192000000 10037280464 10423570432 1092468992 11.766 100 68 134 4 1146951760 40227216 14255552
01:55:05 98285 8192000000 10075382016 10486484992 1108481024 11.918 100 39 78 2 1199965664 82779472 8705168
01:57:50 42739 8192000000 10091409536 10499977216 1124545232 12.070 100 30 60 0 1209484352 78052176 6886944
01:59:39 3049 8192000000 10151942320 10549026816 1135218624 12.171 100 66 127 3 1260216672 110756720 14241328
02:00:51 3220 8192000000 10195441408 10593869824 1142627600 12.240 100 67 129 0 1298491680 142261776 13602304
02:01:59 18896 8192000000 10187119024 10595962880 1142416240 12.238 100 44 88 1 1300293328 147522704 10354384
02:03:25 3146 8192000000 10191112320 10610393088 1156331888 12.369 100 50 98 0 1295811024 128776272 10702864
02:04:33 3043 8192000000 10253459280 10656944128 1156688304 12.372 100 81 161 1 1354002336 179188016 18126016
02:05:42 21815 8192000000 10234840080 10651009024 1162215664 12.424 100 43 85 0 1357844304 185844448 9784192
02:07:13 9714 8192000000 10266281408 10705858560 1151488624 12.323 99 53 104 1 1375526608 211559152 12478832
02:08:31 6984 8192000000 10246822464 10666463232 1164895072 12.449 100 66 132 2 1349440976 170004288 14541616
02:09:52 1449 8192000000 10237502528 10639425536 1154237472 12.349 99 66 130 1 1326041536 157563632 14240432
02:11:07 10866 8192000000 10250813648 10680020992 1160186416 12.405 100 64 126 0 1345658160 171167728 14304016
02:12:24 2404 8192000000 10258103200 10659418112 1162919776 12.431 100 79 154 1 1346512080 166830496 16761808
02:13:37 4292 8192000000 10266991136 10668302336 1152221856 12.330 100 73 143 0 1368304048 199237984 16844208
02:14:49 5015 8192000000 10284094144 10684887040 1166579040 12.465 100 79 156 1 1377383088 193170976 17633072
02:16:00 2926 8192000000 10323411424 10716749824 1153740832 12.345 99 88 174 1 1417112544 243448672 19923040
02:17:09 2225 8192000000 10362779696 10759299072 1143837744 12.252 99 85 169 1 1447892864 284468848 19586272
02:18:23 2418 8192000000 10389205360 10782953472 1139922784 12.215 99 82 164 2 1487682288 328757808 19001696
02:19:40 1724 8192000000 10438930192 10837061632 1143306176 12.247 100 89 176 2 1538348784 374690464 20352144
02:20:46 2757 8192000000 10452242352 10843938816 1121368016 12.040 100 87 174 2 1544202384 401628352 21206016
02:21:56 2435 8192000000 10353893760 10764591104 1137930080 12.196 100 92 182 4 1457727968 298116304 21681584
02:23:07 2148 8192000000 10340746608 10751651840 1140733328 12.222 99 92 178 1 1431142816 270025120 20384368
02:24:13 4737 8192000000 10388218784 10780377088 1138761760 12.204 99 70 140 0 1475275680 319789936 16723984
02:25:28 2383 8192000000 10407909984 10803015680 1125040912 12.075 99 75 148 2 1494214608 352273616 16900080
02:26:37 1874 8192000000 10443228304 10861211648 1143102192 12.245 100 75 148 3 1559295520 397873056 18320272
02:27:54 3780 8192000000 10473646208 10874290176 1152976768 12.337 100 91 182 0 1557697824 383461744 21259312
02:29:03 11083 8192000000 10431602944 10895060992 1158699184 12.391 100 38 76 1 1519515296 351405264 9410848
02:30:16 8167 8192000000 10423876992 10868453376 1130193488 12.123 99 33 66 1 1512866880 373574112 9099280
02:31:46 5921 8192000000 10416118112 10844356608 1138585952 12.202 100 49 98 1 1507203040 356738416 11878672
02:33:04 68781 8192000000 10201621840 10657644544 1129419808 12.116 100 39 78 1 1358212848 219202528 9590512
02:35:50 17674 8192000000 10252527216 10678714368 1123139664 12.057 99 44 87 3 1335978576 203489584 9349328
02:37:33 62821 8192000000 10125904208 10568466432 1129930832 12.121 100 21 42 0 1204786560 70148320 4707408
02:39:46 85235 8192000000 10133890464 10565238784 1122342944 12.049 99 29 58 2 1194354352 65831968 6179440
02:42:19 27557 8192000000 10080519136 10572148736 1111633584 11.948 99 11 22 1 1165663808 51776960 2253264
02:43:48 69998 8192000000 10124641072 10559094784 1133148416 12.151 100 14 28 2 1206992880 70870912 2973552
02:46:06 47030 8192000000 10130822448 10582843392 1137713008 12.194 100 15 30 1 1225325344 84281968 3330368
02:48:02 89098 8192000000 10060268112 10554150912 1127776032 12.100 99 17 34 2 1195872096 64171152 3924912
02:50:44 43991 8192000000 10083396128 10523123712 1136522016 12.183 99 23 46 0 1202021568 60830576 4668976
02:52:34 41315 8192000000 10163597696 10602143744 1156109648 12.367 100 48 96 1 1252972720 86909936 9953136
02:54:27 24176 8192000000 10140577168 10641448960 1142404240 12.238 99 19 38 2 1255074944 108279072 4391632
02:55:52 60702 8192000000 10099272912 10569023488 1128185200 12.104 98 16 32 1 1187522368 56167360 3169808
02:57:54 31499 8192000000 10116705792 10580025344 1124903104 12.073 99 15 29 1 1206958688 78469776 3585808
02:59:27 66338 8192000000 10079678032 10556170240 1128851392 12.111 99 24 48 1 1209709136 75565472 5292272
03:01:37 11935 8192000000 10082933648 10514317312 1142484464 12.239 100 48 96 1 1183108768 31126576 9497728
03:02:56 73024 8192000000 10096225856 10599022592 1123316320 12.058 98 27 54 0 1211244208 82595792 5332096
03:05:18 49986 8192000000 10073099408 10534424576 1107714080 11.911 97 21 42 0 1146525376 34395040 4416256
03:07:12 93328 8192000000 10067384240 10566819840 1069844176 11.551 94 17 34 0 1145113280 71997648 3271456
03:09:58 12734 8192000000 10089297648 10507374592 1122360592 12.049 99 49 95 0 1171305008 38698112 10246304
03:11:12 126250 8192000000 10077401424 10532098048 1108663920 11.920 99 23 46 0 1172059648 58283344 5112384
03:14:19 63957 8192000000 10080923136 10549968896 1119671024 12.024 99 10 20 0 1170730064 48588640 2470400
03:16:24 30154 8192000000 10066557728 10545139712 1121382048 12.040 99 10 20 0 1161762496 38214528 2165920
03:17:54 80181 8192000000 10041404032 10529243136 1060481264 11.461 94 17 34 2 1137274176 73129776 3663136
03:20:15 29609 8192000000 10047150720 10475888640 1072201232 11.573 96 20 40 0 1110447616 33410928 4835456
03:21:46 63517 8192000000 10064449968 10547838976 1054312848 11.402 95 14 29 1 1101970576 44837232 2820496
03:23:51 71613 8192000000 10075346368 10529476608 1086520416 11.710 97 21 42 1 1142184240 51930208 3733616
03:26:06 83127 8192000000 10087659552 10522808320 1085214384 11.697 98 19 38 1 1139747376 50609552 3923440
03:28:31 66513 8192000000 10075217424 10495561728 1085008016 11.695 98 8 16 0 1152601392 66156016 1437360
03:30:38 64792 8192000000 10072496096 10548686848 1106156928 11.896 99 10 21 2 1157281888 48966720 2158240
03:32:57 80798 8192000000 10039325136 10488532992 1115458112 11.984 100 16 32 1 1148774944 30183280 3133552
03:35:22 53717 8192000000 10032027440 10483052544 1107833600 11.912 99 6 13 0 1134408384 25505456 1069328
03:37:16 52736 8192000000 10065423024 10529890304 1111567376 11.947 99 15 30 1 1187437536 72247744 3622416
03:39:10 29706 8192000000 10051416944 10500808704 1124005184 12.065 100 22 44 0 1161557536 33078560 4473792
03:40:41 27091 8192000000 10103989040 10536194048 1140674416 12.222 100 38 76 3 1202659952 54590752 7394784
03:42:25 42052 8192000000 10150484896 10586066944 1131772800 12.138 99 19 38 0 1223559040 87488448 4297792
03:44:08 95476 8192000000 10168014688 10679349248 1134444784 12.163 99 11 22 0 1250516272 113584336 2487152
03:46:54 55134 8192000000 10050703776 10506846208 1085416720 11.699 96 14 28 1 1130506928 42613584 2476624
03:48:58 84411 8192000000 10057765312 10521731072 1114236448 11.973 99 16 32 4 1155663936 37806560 3620928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-08-2020 03:54:57 to 20-08-2020 03:55:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 90 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

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

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          217560
MON$OLDEST_ACTIVE               218208
MON$OLDEST_SNAPSHOT             218208
MON$NEXT_TRANSACTION            218208
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                       2116160
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             677
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2166 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2166 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2166 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.2166 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Thu Aug 20 03:52:50 2020
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
--- end of diff output ---

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

20.08.20 03:55:16. 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-20 03:55:19 NO_SEVERE_ERRORS_FOUND
20.08.20 03:55:21. End of report.