Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.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 16814624 kB
MemAvailable 30950276 kB
Buffers 0 kB
Cached 14842040 kB
SwapCached 5936 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-16 12:51:51.3300
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/48668
TRN_ID                          207156
ATT_ID                          475
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: 8162.62 24184 979514
customer order: creation 150.79 440 18095
customer order: refuse 12.63 500 1515
order to supplier: creation 39.30 1608 4716
order to supplier: removal 2.95 152655 354
invoice (draft): creation 40.67 4591 4880
invoice (draft): removal 46.14 1105 5537
invoice accept: apply 36.97 21080 4436
invoice accept: cancel 41.56 1261 4987
customer reserve: creation 3459.97 200 415196
customer reserve: removal 4077.46 87 489295
realization accept: apply 72.50 219 8700
realization accept: cancel 70.19 163 8423
payment from customer: creation 51.64 127 6197
payment from customer: removal 5.33 107 640
payment to supplier: creation 42.37 168 5084
payment to supplier: removal 3.21 110 385
service: total inventory turnovers 3.13 5349 375
service: total monetary turnovers 4.98 872 598
service: aggregate perf. data 0.81 19400 97
service: refresh index statistics 0.03 273643 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-08-2020 12:52:52 to 16-08-2020 12:52:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3671
TEST_TIME 2 3471
TEST_TIME 3 3965
TEST_TIME 4 3980
TEST_TIME 5 3524
TEST_TIME 6 3496
TEST_TIME 7 3591
TEST_TIME 8 4043
TEST_TIME 9 4454
TEST_TIME 10 4173
TEST_TIME 11 4486
TEST_TIME 12 4581
TEST_TIME 13 4635
TEST_TIME 14 4691
TEST_TIME 15 4684
TEST_TIME 16 4949
TEST_TIME 17 5260
TEST_TIME 18 5478
TEST_TIME 19 5439
TEST_TIME 20 5546
TEST_TIME 21 5944
TEST_TIME 22 6032
TEST_TIME 23 6191
TEST_TIME 24 6559
TEST_TIME 25 6778
TEST_TIME 26 7171
TEST_TIME 27 7321
TEST_TIME 28 7217
TEST_TIME 29 7237
TEST_TIME 30 7401
TEST_TIME 31 7434
TEST_TIME 32 7605
TEST_TIME 33 7655
TEST_TIME 34 8388
TEST_TIME 35 9125
TEST_TIME 36 9226
TEST_TIME 37 9108
TEST_TIME 38 9270
TEST_TIME 39 9157
TEST_TIME 40 9015
TEST_TIME 41 8835
TEST_TIME 42 8839
TEST_TIME 43 8687
TEST_TIME 44 8617
TEST_TIME 45 8575
TEST_TIME 46 8592
TEST_TIME 47 8522
TEST_TIME 48 8582
TEST_TIME 49 8635
TEST_TIME 50 8584
TEST_TIME 51 8580
TEST_TIME 52 8457
TEST_TIME 53 8424
TEST_TIME 54 8314
TEST_TIME 55 8280
TEST_TIME 56 8226
TEST_TIME 57 8242
TEST_TIME 58 8155
TEST_TIME 59 8135
TEST_TIME 60 8101
TEST_TIME 61 8051
TEST_TIME 62 8055
TEST_TIME 63 8006
TEST_TIME 64 8040
TEST_TIME 65 8105
TEST_TIME 66 8105
TEST_TIME 67 8099
TEST_TIME 68 8205
TEST_TIME 69 8228
TEST_TIME 70 8187
TEST_TIME 71 8187
TEST_TIME 72 8362
TEST_TIME 73 8328
TEST_TIME 74 8418
TEST_TIME 75 8421
TEST_TIME 76 8416
TEST_TIME 77 8327
TEST_TIME 78 8239
TEST_TIME 79 8292
TEST_TIME 80 8213
TEST_TIME 81 8167
TEST_TIME 82 8188
TEST_TIME 83 8148
TEST_TIME 84 8190
TEST_TIME 85 8197
TEST_TIME 86 8157
TEST_TIME 87 8241
TEST_TIME 88 8251
TEST_TIME 89 8259
TEST_TIME 90 8313
TEST_TIME 91 8280
TEST_TIME 92 8314
TEST_TIME 93 8403
TEST_TIME 94 8421
TEST_TIME 95 8340
TEST_TIME 96 8276
TEST_TIME 97 8233
TEST_TIME 98 8171
TEST_TIME 99 8160
TEST_TIME 100 8129
TEST_TIME 101 8130
TEST_TIME 102 8134
TEST_TIME 103 8157
TEST_TIME 104 8153
TEST_TIME 105 8130
TEST_TIME 106 8202
TEST_TIME 107 8259
TEST_TIME 108 8263
TEST_TIME 109 8249
TEST_TIME 110 8270
TEST_TIME 111 8295
TEST_TIME 112 8258
TEST_TIME 113 8195
TEST_TIME 114 8214
TEST_TIME 115 8187
TEST_TIME 116 8186
TEST_TIME 117 8169
TEST_TIME 118 8163
TEST_TIME 119 8157
TEST_TIME 120 8168

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-08-2020 12:52:55 to 16-08-2020 12:52:58.

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 1361578 1361578 0 0.00 0 23715 8 0 0
doc_list_biud 1361568 1361568 0 0.00 0 71862 39 0 0
sp_add_invoice_to_stock 4519 4436 83 1.83 43 200244 21080 0 83
sp_cancel_adding_invoice 4987 4987 0 0.00 5 90921 1262 0 0
sp_cancel_client_order 1515 1515 0 0.00 4 10417 500 0 0
sp_cancel_customer_reserve 489295 489295 0 0.00 1 71865 88 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 3 3151 108 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 2 5874 111 0 0
sp_cancel_supplier_invoice 5544 5537 7 0.12 2 40602 1106 0 7
sp_cancel_supplier_order 354 354 0 0.00 7 1391371 152656 0 0
sp_cancel_write_off 8423 8423 0 0.00 1 16328 163 0 0
sp_client_order 18095 18095 0 0.00 3 28550 440 0 0
sp_customer_reserve 415196 415196 0 0.00 0 70584 201 0 0
sp_fill_shopping_cart 27666 27666 0 0.00 1 37755 60 0 0
sp_fill_shopping_cart_clo_res 415180 415180 0 0.00 0 31453 24 0 0
sp_get_clo_for_invoice 4420 4420 0 0.00 0 40461 517 0 0
sp_kill_cost_storno 14435 14435 0 0.00 0 16324 103 0 0
sp_kill_qstorno_ret_qs2qd 992904 992904 0 0.00 0 71857 47 0 0
sp_kill_qty_storno 523230 523230 0 0.00 0 71861 101 0 0
sp_lock_selected_doc 18231 18231 0 0.00 0 6795 6 0 0
sp_make_cost_storno 24401 24401 0 0.00 0 13237 78 0 0
sp_multiply_rows_for_pdistr 24400 24400 0 0.00 0 11827 43 0 0
sp_multiply_rows_for_qdistr 18095 18095 0 0.00 0 28544 252 0 0
sp_pay_from_customer 6197 6197 0 0.00 4 8042 127 0 0
sp_pay_to_supplier 5084 5084 0 0.00 4 16428 168 0 0
sp_payment_common 11281 11281 0 0.00 1 10066 29 0 0
sp_qd_handle_on_cancel_clo 1515 1515 0 0.00 0 10408 470 0 0
sp_qd_handle_on_invoice_upd_sts 9406 9406 0 0.00 0 23641 473 0 0
sp_qd_handle_on_reserve_upd_sts 8700 8700 0 0.00 0 10113 59 0 0
sp_reserve_write_off 8700 8700 0 0.00 6 19490 220 0 0
sp_supplier_invoice 4880 4880 0 0.00 7 104962 4592 0 0
sp_supplier_order 4716 4716 0 0.00 4 42969 1609 0 0
srv_aggregate_perf_data 97 97 0 0.00 2329 271290 19400 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1450 146638 41213 0 0
srv_find_qd_qs_mism 936271 936271 0 0.00 0 23714 6 0 0
srv_make_invnt_saldo 375 375 0 0.00 50 118440 5349 0 0
srv_make_money_saldo 598 598 0 0.00 1 13827 872 0 0
srv_recalc_idx_stat 4 4 0 0.00 54189 481726 273643 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 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 4437 4437 0 0.00 0 10708 245 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 1 3420 171 0 0
t$perf-norm:sp_cancel_client_order 1515 1515 0 0.00 0 1813 17 0 0
t$perf-norm:sp_cancel_customer_reserve 607 607 0 0.00 0 4317 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 1167 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 1752 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 364 364 0 0.00 0 911 18 0 0
t$perf-norm:sp_cancel_supplier_order 354 354 0 0.00 0 33140 2648 0 0
t$perf-norm:sp_cancel_write_off 874 874 0 0.00 0 2268 11 0 0
t$perf-norm:sp_client_order 18095 18095 0 0.00 0 11785 25 0 0
t$perf-norm:sp_customer_reserve 926 926 0 0.00 0 7354 15 0 0
t$perf-norm:sp_pay_from_customer 6197 6197 0 0.00 0 5521 13 0 0
t$perf-norm:sp_pay_to_supplier 5084 5084 0 0.00 0 9095 16 0 0
t$perf-norm:sp_reserve_write_off 8700 8700 0 0.00 0 23767 17 0 0
t$perf-norm:sp_supplier_invoice 4880 4880 0 0.00 0 2973 22 0 0
t$perf-norm:sp_supplier_order 4716 4716 0 0.00 0 4938 23 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 497 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1026 17 0 0
t$perf-norm:srv_make_invnt_saldo 375 375 0 0.00 0 2016 20 0 0
t$perf-norm:srv_make_money_saldo 598 598 0 0.00 0 970 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 6 5 0 0
v_add_invoice_to_stock 9000 8834 166 1.84 0 7095 15 0 166
v_all_customers 32846 32846 0 0.00 0 24 1 0 0
v_all_suppliers 26590 26590 0 0.00 0 229 1 0 0
v_all_wares 36190 36190 0 0.00 0 43 1 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 194 7 0 0
v_cancel_client_order 3030 3030 0 0.00 0 1173 4 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 608 4 0 0
v_cancel_customer_reserve 1214 1214 0 0.00 0 886 6 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 5863 18 0 0
v_cancel_supplier_invoice 742 728 14 1.88 0 563 13 0 14
v_cancel_supplier_order 708 708 0 0.00 0 1541 9 0 0
v_cancel_write_off 1748 1748 0 0.00 0 4301 16 0 0
v_max_id_clo_ord 4704 4704 0 0.00 0 2705 3 0 0
v_max_id_clo_res 926 926 0 0.00 0 4994 25 0 0
v_max_id_ord_sup 4867 4867 0 0.00 0 4279 7 0 0
v_max_non_paid_invoice 1360 1360 0 0.00 0 1175 3 0 0
v_max_non_paid_realizn 3241 3241 0 0.00 0 4410 5 0 0
v_min_id_clo_ord 4704 4704 0 0.00 0 3893 10 0 0
v_min_id_clo_res 926 926 0 0.00 0 9852 37 0 0
v_min_id_ord_sup 4867 4867 0 0.00 0 17957 15 0 0
v_min_non_paid_invoice 5084 5084 0 0.00 0 10060 23 0 0
v_min_non_paid_realizn 6197 6197 0 0.00 0 6014 18 0 0
v_our_firm 9256 9256 0 0.00 0 22 1 0 0
v_reserve_write_off 17400 17400 0 0.00 0 4434 5 0 0
x_lock_depdocs_on_canc_invoice 10160 10160 0 0.00 0 12581 168 0 0
x_lock_depdocs_on_canc_sup_ord 354 354 0 0.00 0 5040 513 0 0
x_make_qstorno_1000_1200 4716 4716 0 0.00 1 42894 1505 0 0
x_make_qstorno_1200_2000 4880 4880 0 0.00 3 89123 4345 0 0
x_make_qstorno_2100_3300 415196 415196 0 0.00 0 70567 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-08-2020 12:52:59 to 16-08-2020 12:53:03.

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:54 5 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
10:22:56 26152 8192000000 9711712272 10001801216 791906656 8.814 100 92 184 0 821771024 7610112 22254256
10:24:29 14510 8192000000 9749448352 10050719744 828125136 9.180 100 86 172 1 860191008 11451312 20614560
10:25:48 22650 8192000000 9776762384 10089107456 851845680 9.419 100 87 174 3 890648608 18274544 20528384
10:27:16 29694 8192000000 9851610480 10174218240 896572448 9.864 100 84 168 0 957145728 41761232 18812048
10:28:52 15510 8192000000 9906219120 10239795200 933982208 10.234 100 84 168 2 1012080128 59679888 18418032
10:30:14 9706 8192000000 9934303344 10279628800 966138832 10.549 100 75 149 2 1047558000 65799936 15619232
10:31:27 6891 8192000000 9945694528 10296692736 990403760 10.785 100 82 163 0 1059355440 52534672 16417008
10:32:37 7404 8192000000 9981253568 10340954112 1008879264 10.965 100 72 144 3 1089936048 66979168 14077616
10:33:48 2546 8192000000 10026508224 10387505152 1039624064 11.261 100 86 171 0 1140603168 83406432 17572672
10:34:54 2766 8192000000 10026538192 10393296896 1053647840 11.396 100 94 188 0 1137689440 64866464 19175136
10:36:00 10504 8192000000 10040999104 10410749952 1060549312 11.462 100 79 158 0 1157993504 81358560 16085632
10:37:13 2928 8192000000 10019142592 10405322752 1066334416 11.517 100 45 90 0 1149432256 73884880 9212960
10:38:17 52404 8192000000 10007128000 10385801216 1080792144 11.655 100 55 109 4 1109593728 17077888 11723696
10:40:11 7926 8192000000 9995738784 10389000192 1084250832 11.688 100 36 73 1 1118628768 26725520 7652416
10:41:21 6934 8192000000 9994204720 10397032448 1083982448 11.685 100 30 60 0 1120939872 30497504 6459920
10:42:29 8743 8192000000 9997538480 10395938816 1079109760 11.639 99 18 37 2 1099872832 16781632 3981440
10:43:38 40920 8192000000 10015172704 10406866944 1090469264 11.747 100 46 92 1 1125021776 24421088 10131424
10:45:20 5754 8192000000 9999368624 10396499968 1067201184 11.525 99 53 104 4 1094238560 16170448 10866928
10:46:29 5876 8192000000 9976556672 10359123968 1052622368 11.386 99 35 68 3 1090726976 30017856 8086752
10:47:36 9160 8192000000 9918806768 10296778752 978793008 10.672 96 22 44 1 1003106064 19985424 4327632
10:48:46 36153 8192000000 9920806368 10314747904 976445264 10.650 96 35 69 4 1002007072 17528976 8032832
10:50:23 9240 8192000000 9912778080 10303123456 1005694944 10.934 99 35 70 1 1038023216 24755808 7572464
10:51:33 7307 8192000000 9958566752 10354012160 1051987744 11.380 100 27 53 3 1074506656 16177568 6341344
10:52:50 12851 8192000000 10013431312 10393493504 1063615744 11.491 99 54 106 3 1099619280 24501952 11501584
10:54:06 60472 8192000000 10034788080 10437763072 1083671968 11.682 100 43 85 1 1149726736 55678256 10376512
10:56:27 47778 8192000000 10073558480 10472775680 1095766048 11.797 100 41 82 1 1164151312 59234080 9151184
10:58:18 50895 8192000000 10135964752 10542305280 1114287920 11.973 100 43 86 2 1234860640 111431584 9141136
11:00:16 40582 8192000000 10129055792 10557759488 1122310288 12.049 100 27 54 0 1237734832 109342656 6081888
11:02:08 20222 8192000000 10194263120 10602504192 1138250704 12.199 100 57 115 2 1299192688 148064400 12877584
11:03:42 4270 8192000000 10214249632 10614210560 1151476512 12.323 100 71 142 1 1313107952 146323824 15307616
11:04:51 6030 8192000000 10252527216 10662416384 1154955952 12.356 100 52 104 2 1360368064 193749536 11662576
11:06:04 9037 8192000000 10256560720 10667065344 1163260864 12.434 100 59 118 0 1357714800 181620160 12833776
11:07:18 4187 8192000000 10289716352 10707730432 1170483440 12.501 100 71 138 0 1392707104 206324224 15899440
11:08:31 2989 8192000000 10365154624 10769199104 1184390176 12.631 100 80 160 0 1463275248 261310608 17574464
11:09:42 1448 8192000000 10339793648 10745602048 1186359616 12.649 100 84 168 0 1441180512 235545136 19275760
11:10:53 3255 8192000000 10406171472 10813730816 1194042720 12.721 100 74 146 2 1508031504 297308656 16680128
11:12:03 3323 8192000000 10443701872 10852397056 1197049632 12.749 100 86 170 0 1541858032 324905248 19903152
11:13:11 2066 8192000000 10421034800 10845679616 1186359936 12.649 100 76 152 1 1514486832 309692768 18434128
11:14:18 5166 8192000000 10447417216 10852163584 1166696752 12.466 99 82 165 1 1531193536 345568368 18928416
11:15:27 2244 8192000000 10459317056 10868998144 1187101856 12.656 100 80 156 2 1556677744 351308544 18267344
11:16:42 5604 8192000000 10438390000 10850111488 1194600160 12.726 100 83 167 1 1533332960 319139728 19593072
11:17:57 17529 8192000000 10392533536 10827685888 1151763072 12.326 98 48 94 3 1478183024 314274544 12145408
11:19:23 5108 8192000000 10417922928 10839330816 1178032544 12.572 100 57 114 0 1521174480 329923680 13218256
11:20:45 2230 8192000000 10456995680 10872676352 1164731600 12.448 100 82 166 0 1550055904 366208864 19115440
11:21:55 6198 8192000000 10464000816 10896896000 1176483376 12.557 100 45 90 1 1569184784 381581248 11120160
11:23:18 14658 8192000000 10492298608 10914283520 1140271776 12.218 100 38 76 1 1574079040 423616128 10191136
11:24:36 14210 8192000000 10438487136 10900901888 1103019200 11.866 98 34 68 1 1517657360 405840416 8797744
11:26:01 18918 8192000000 10246171104 10735456256 1121126704 12.038 100 33 66 0 1374935216 245937200 7871312
11:27:25 13313 8192000000 10117155456 10534019072 1131746832 12.138 99 54 111 0 1195695216 52230096 11718288
11:28:41 42613 8192000000 10129286048 10592382976 1114707568 11.977 99 16 32 1 1216060528 97812624 3540336
11:30:26 72238 8192000000 10068101056 10513776640 1111811568 11.950 99 34 66 4 1173823744 54421904 7590272
11:33:25 46220 8192000000 10062834144 10508333056 1101449376 11.851 98 34 68 3 1155306048 45760656 8096016
11:35:14 27622 8192000000 10109371872 10548596736 1108007360 11.914 99 29 58 0 1198523728 84439952 6076416
11:36:53 38587 8192000000 10093269376 10572447744 1109780880 11.930 99 24 48 2 1194665456 79266224 5618352
11:38:33 74478 8192000000 10122846848 10600685568 1131830496 12.139 100 20 40 0 1241807104 105262816 4713792
11:41:02 61282 8192000000 10079355200 10538405888 1102750384 11.864 98 26 52 1 1151496688 43207440 5538864
11:43:03 57530 8192000000 10081682832 10568138752 1087815152 11.722 97 4 8 0 1159002512 69947168 1240192
11:45:03 82446 8192000000 10050431568 10490318848 1111408416 11.946 99 26 53 0 1151473344 34487040 5577888
11:47:27 34537 8192000000 10048232064 10505908224 1083328896 11.679 97 8 16 0 1135653264 50536528 1787840
11:49:11 43413 8192000000 10053086880 10489933824 1115893312 11.988 100 29 56 1 1163143856 40771120 6479424
11:51:04 82210 8192000000 10061458768 10523140096 1115486704 11.984 99 12 24 0 1165975392 47924848 2563840
11:53:43 56390 8192000000 10067870560 10528927744 1104064000 11.876 98 14 28 1 1135330768 28687328 2579440
11:55:40 66347 8192000000 10065364032 10541404160 1120566064 12.032 99 15 30 2 1193250928 69808368 2876496
11:57:48 68232 8192000000 10129149296 10565775360 1113151760 11.962 98 15 30 2 1176814720 60491504 3171456
12:00:04 40346 8192000000 10087772608 10547531776 1133870624 12.158 100 23 46 3 1193722912 54553232 5299056
12:01:45 60387 8192000000 10172533440 10606493696 1130032816 12.122 99 36 72 1 1235643872 98186400 7424656
12:03:47 21747 8192000000 10071199360 10506088448 1141138352 12.226 100 20 40 1 1182008672 36654720 4215600
12:05:10 80247 8192000000 10081058080 10576150528 1109570832 11.928 97 12 24 1 1181504528 69410336 2523360
12:07:34 110299 8192000000 10097341952 10552889344 1132953472 12.149 99 20 40 0 1202611008 65414992 4242544
12:11:03 44484 8192000000 10058831056 10562772992 1131052752 12.131 100 6 12 0 1172933520 40854640 1026128
12:12:49 40331 8192000000 10082156256 10550407168 1134296560 12.162 99 12 24 0 1181600592 44590192 2713840
12:14:50 38885 8192000000 10060787216 10520817664 1114128592 11.971 99 29 57 1 1181610464 61265664 6216208
12:16:36 43962 8192000000 10067139984 10501709824 1081561184 11.662 97 14 28 0 1135274800 50977328 2736288
12:18:50 59219 8192000000 10062540432 10500595712 1077942176 11.628 97 15 30 2 1154144832 73018704 3183952
12:21:01 93081 8192000000 10083953600 10522161152 1099777696 11.836 97 26 52 2 1172231296 67071712 5381888
12:23:36 65692 8192000000 10117102336 10567966720 1093685360 11.778 97 26 52 0 1155096512 56494224 4916928
12:25:48 89348 8192000000 10057928064 10549657600 1100321856 11.841 98 6 12 0 1143439760 42073424 1044480
12:28:45 68978 8192000000 10049922544 10501603328 1115642816 11.986 100 10 20 0 1141940192 24059872 2237504
12:31:09 54239 8192000000 10056639008 10502180864 1091543216 11.757 97 16 33 0 1121491216 26457312 3490688
12:33:05 52381 8192000000 10088517952 10537295872 1127097392 12.094 100 17 34 0 1178613776 47588688 3927696
12:35:12 58780 8192000000 10055065712 10483630080 1067843168 11.531 95 22 44 0 1115325360 42823152 4659040
12:37:33 146638 8192000000 10053235952 10519744512 1080234544 11.650 96 20 40 0 1163121632 78439056 4448032
12:40:59 44305 8192000000 10079410432 10531811328 1113281408 11.963 99 25 50 0 1157044320 38129488 5633424
12:42:49 32355 8192000000 10055918096 10469957632 1089900128 11.742 97 28 55 2 1121140608 24918144 6322336
12:44:25 86975 8192000000 10071008896 10553757696 1124474176 12.069 100 27 54 1 1201195632 70990832 5730624
12:46:55 30462 8192000000 10077928960 10585309184 1128094032 12.103 100 3 6 0 1179112688 50457456 561200
12:49:07 30859 8192000000 10087513024 10526916608 1136224496 12.180 100 24 46 2 1186776800 45026768 5525536
12:50:49 56017 8192000000 10100847024 10555793408 1133359728 12.153 99 34 68 1 1208096816 67919312 6817776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-08-2020 12:53:05 to 16-08-2020 12:53:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 83 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 16-08-2020 12:53:20 to 16-08-2020 12:53:22.

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          206499
MON$OLDEST_ACTIVE               207875
MON$OLDEST_SNAPSHOT             207875
MON$NEXT_TRANSACTION            207875
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                       2046432
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             660
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2158 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2158 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2158 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.2158 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 ---
:94: 
:95: mscs-test-161.budzdorov.ru    Sun Aug 16 12:51:52 2020
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
:98: 
:99: mscs-test-161.budzdorov.ru    Sun Aug 16 12:51:55 2020
:100:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:101: 
:102: 
:103: mscs-test-161.budzdorov.ru    Sun Aug 16 12:51:55 2020
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:105: 
:106: 
:107: mscs-test-161.budzdorov.ru    Sun Aug 16 12:51:58 2020
:108:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:109: 
--- end of diff output ---

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

16.08.20 12:53:24. 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-16 12:53:26 NO_SEVERE_ERRORS_FOUND
16.08.20 12:53:28. End of report.