Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.08.2020 09: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 16521176 kB
MemAvailable 30918404 kB
Buffers 0 kB
Cached 15111500 kB
SwapCached 6000 kB
Command: fdisk -l

Server and database settinfs

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


Test configuration settings

param=fbc, val=/opt/fb40/bin
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=report_compress_cmd, val=/usr/bin/7za
param=host, val=localhost
param=port, val=3400
param=usr, val=SYSDBA
param=pwd, val=masterkey
param=tmpdir, val=/var/tmp/logs.oltp40
param=remove_isql_logs, val=if_no_severe_errors
param=working_mode, val=small_03
param=actions_todo_before_reconnect, val=300
param=max_cps, val=25
param=sleep_max, val=0
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=no_auto_undo, val=1
param=recalc_idx_min_interval, val=30
param=used_in_replication, val=0
param=separate_workers, val=1
param=update_conflict_percent, val=0
param=unit_selection_method, val=random
param=detailed_info, val=0
param=mon_unit_perf, val=2
param=mon_unit_list, val=//
param=mon_query_interval, val=60
param=halt_test_on_errors, val=/CK/
param=qmism_verify_bitset, val=1
param=warm_time, val=30
param=test_time, val=120
param=test_intervals, val=60
param=create_with_fw, val=sync
param=create_with_sweep, val=0
param=wait_if_not_exists, val=0
param=wait_after_create, val=0
param=init_docs, val=0
param=expected_workers, val=100
param=init_buff, val=10000
param=wait_for_copy, val=1
param=create_with_debug_objects, val=1
param=create_with_split_heavy_tabs, val=1
param=create_with_separate_qdistr_idx, val=0
param=create_with_compound_columns_order, val=most_selective_first
param=replace_instance, val=1
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=make_html, val=1
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=file_name_with_test_params, val=regular
param=file_name_this_host_info, val=no_host_info
param=gather_hardware_info, val=1
param=use_mtee, val=0
param=is_embed, val=0
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED.  
DTS_END                         2020-08-19 09:53:54.8700
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/34418
TRN_ID                          218560
ATT_ID                          536
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: 8513.17 23362 1021581
customer order: creation 155.28 421 18633
customer order: refuse 13.43 437 1612
order to supplier: creation 41.64 1629 4997
order to supplier: removal 3.38 127808 405
invoice (draft): creation 41.13 4557 4935
invoice (draft): removal 49.43 1019 5932
invoice accept: apply 37.62 21494 4514
invoice accept: cancel 44.42 917 5330
customer reserve: creation 3534.97 206 424196
customer reserve: removal 4322.26 77 518671
realization accept: apply 75.73 196 9088
realization accept: cancel 77.78 138 9333
payment from customer: creation 54.24 128 6509
payment from customer: removal 4.72 105 566
payment to supplier: creation 44.38 148 5325
payment to supplier: removal 3.31 81 397
service: total inventory turnovers 3.30 4753 396
service: total monetary turnovers 5.34 905 641
service: aggregate perf. data 0.82 14396 98
service: refresh index statistics 0.03 287828 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-08-2020 09:54:37 to 19-08-2020 09:54:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1726
TEST_TIME 2 2204
TEST_TIME 3 2884
TEST_TIME 4 3075
TEST_TIME 5 3157
TEST_TIME 6 3064
TEST_TIME 7 3105
TEST_TIME 8 3233
TEST_TIME 9 3182
TEST_TIME 10 3642
TEST_TIME 11 4090
TEST_TIME 12 4132
TEST_TIME 13 4166
TEST_TIME 14 4268
TEST_TIME 15 4527
TEST_TIME 16 4603
TEST_TIME 17 4694
TEST_TIME 18 4704
TEST_TIME 19 4763
TEST_TIME 20 4817
TEST_TIME 21 4944
TEST_TIME 22 5185
TEST_TIME 23 5265
TEST_TIME 24 5476
TEST_TIME 25 5916
TEST_TIME 26 6111
TEST_TIME 27 6391
TEST_TIME 28 6847
TEST_TIME 29 6979
TEST_TIME 30 7281
TEST_TIME 31 7422
TEST_TIME 32 7763
TEST_TIME 33 8014
TEST_TIME 34 8684
TEST_TIME 35 8606
TEST_TIME 36 8585
TEST_TIME 37 8403
TEST_TIME 38 8207
TEST_TIME 39 8353
TEST_TIME 40 8402
TEST_TIME 41 8358
TEST_TIME 42 8468
TEST_TIME 43 8407
TEST_TIME 44 8323
TEST_TIME 45 8360
TEST_TIME 46 8448
TEST_TIME 47 8388
TEST_TIME 48 8376
TEST_TIME 49 8317
TEST_TIME 50 8299
TEST_TIME 51 8227
TEST_TIME 52 8138
TEST_TIME 53 8267
TEST_TIME 54 8231
TEST_TIME 55 8210
TEST_TIME 56 8195
TEST_TIME 57 8190
TEST_TIME 58 8137
TEST_TIME 59 8102
TEST_TIME 60 8125
TEST_TIME 61 8237
TEST_TIME 62 8275
TEST_TIME 63 8408
TEST_TIME 64 8383
TEST_TIME 65 8451
TEST_TIME 66 8474
TEST_TIME 67 8496
TEST_TIME 68 8687
TEST_TIME 69 8759
TEST_TIME 70 8747
TEST_TIME 71 8664
TEST_TIME 72 8556
TEST_TIME 73 8567
TEST_TIME 74 8591
TEST_TIME 75 8532
TEST_TIME 76 8475
TEST_TIME 77 8442
TEST_TIME 78 8418
TEST_TIME 79 8380
TEST_TIME 80 8460
TEST_TIME 81 8447
TEST_TIME 82 8413
TEST_TIME 83 8401
TEST_TIME 84 8483
TEST_TIME 85 8533
TEST_TIME 86 8502
TEST_TIME 87 8482
TEST_TIME 88 8495
TEST_TIME 89 8514
TEST_TIME 90 8612
TEST_TIME 91 8655
TEST_TIME 92 8569
TEST_TIME 93 8514
TEST_TIME 94 8530
TEST_TIME 95 8506
TEST_TIME 96 8489
TEST_TIME 97 8533
TEST_TIME 98 8488
TEST_TIME 99 8503
TEST_TIME 100 8499
TEST_TIME 101 8521
TEST_TIME 102 8553
TEST_TIME 103 8531
TEST_TIME 104 8599
TEST_TIME 105 8625
TEST_TIME 106 8633
TEST_TIME 107 8625
TEST_TIME 108 8546
TEST_TIME 109 8518
TEST_TIME 110 8485
TEST_TIME 111 8441
TEST_TIME 112 8413
TEST_TIME 113 8409
TEST_TIME 114 8402
TEST_TIME 115 8485
TEST_TIME 116 8476
TEST_TIME 117 8467
TEST_TIME 118 8480
TEST_TIME 119 8483
TEST_TIME 120 8515

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-08-2020 09:54:41 to 19-08-2020 09:54:44.

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 1413112 1413112 0 0.00 0 18676 8 0 0
doc_list_biud 1413112 1413112 0 0.00 0 42867 36 0 0
sp_add_invoice_to_stock 4625 4514 111 2.40 39 218816 21494 0 111
sp_cancel_adding_invoice 5330 5330 0 0.00 4 76004 917 0 0
sp_cancel_client_order 1612 1612 0 0.00 4 10921 438 0 0
sp_cancel_customer_reserve 518671 518671 0 0.00 1 42868 77 0 0
sp_cancel_pay_from_customer 566 566 0 0.00 2 4873 105 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 2 3951 82 0 0
sp_cancel_supplier_invoice 5939 5932 7 0.11 1 29952 1020 0 7
sp_cancel_supplier_order 405 405 0 0.00 7 1164614 127808 0 0
sp_cancel_write_off 9333 9333 0 0.00 1 8171 139 0 0
sp_client_order 18633 18633 0 0.00 3 44676 421 0 0
sp_customer_reserve 424196 424196 0 0.00 0 127607 207 0 0
sp_fill_shopping_cart 28555 28555 0 0.00 1 21055 53 0 0
sp_fill_shopping_cart_clo_res 424193 424193 0 0.00 0 21456 22 0 0
sp_get_clo_for_invoice 4511 4511 0 0.00 0 74773 524 0 0
sp_kill_cost_storno 15626 15626 0 0.00 0 5712 69 0 0
sp_kill_qstorno_ret_qs2qd 1053012 1053012 0 0.00 0 42863 42 0 0
sp_kill_qty_storno 554882 554882 0 0.00 0 42867 90 0 0
sp_lock_selected_doc 18850 18850 0 0.00 0 9726 5 0 0
sp_make_cost_storno 25433 25433 0 0.00 0 18644 72 0 0
sp_multiply_rows_for_pdistr 25433 25433 0 0.00 0 8709 37 0 0
sp_multiply_rows_for_qdistr 18633 18633 0 0.00 0 44663 244 0 0
sp_pay_from_customer 6509 6509 0 0.00 4 15564 129 0 0
sp_pay_to_supplier 5325 5325 0 0.00 4 16071 148 0 0
sp_payment_common 11834 11834 0 0.00 1 11395 23 0 0
sp_qd_handle_on_cancel_clo 1612 1612 0 0.00 0 10915 402 0 0
sp_qd_handle_on_invoice_upd_sts 9841 9841 0 0.00 0 25070 422 0 0
sp_qd_handle_on_reserve_upd_sts 9088 9088 0 0.00 0 20746 56 0 0
sp_reserve_write_off 9088 9088 0 0.00 6 20765 197 0 0
sp_supplier_invoice 4935 4935 0 0.00 5 91404 4558 0 0
sp_supplier_order 4997 4997 0 0.00 5 28583 1630 0 0
srv_aggregate_perf_data 98 98 0 0.00 2566 85714 14396 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1713 151757 44190 0 0
srv_find_qd_qs_mism 977604 977604 0 0.00 0 13359 7 0 0
srv_make_invnt_saldo 396 396 0 0.00 41 175972 4754 0 0
srv_make_money_saldo 641 641 0 0.00 1 17123 905 0 0
srv_recalc_idx_stat 3 3 0 0.00 204957 364700 287828 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 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 4515 4515 0 0.00 0 10983 202 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 0 1980 86 0 0
t$perf-norm:sp_cancel_client_order 1612 1612 0 0.00 0 8092 20 0 0
t$perf-norm:sp_cancel_customer_reserve 633 633 0 0.00 0 7561 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 566 566 0 0.00 0 879 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 675 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 339 339 0 0.00 0 2157 19 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 40443 2252 0 0
t$perf-norm:sp_cancel_write_off 927 927 0 0.00 0 1509 9 0 0
t$perf-norm:sp_client_order 18633 18633 0 0.00 0 5343 20 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 4372 10 0 0
t$perf-norm:sp_pay_from_customer 6509 6509 0 0.00 0 4329 10 0 0
t$perf-norm:sp_pay_to_supplier 5325 5325 0 0.00 0 2311 7 0 0
t$perf-norm:sp_reserve_write_off 9088 9088 0 0.00 0 5950 13 0 0
t$perf-norm:sp_supplier_invoice 4935 4935 0 0.00 0 4745 23 0 0
t$perf-norm:sp_supplier_order 4997 4997 0 0.00 0 4200 22 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 449 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1210 43 0 0
t$perf-norm:srv_make_invnt_saldo 396 396 0 0.00 0 853 11 0 0
t$perf-norm:srv_make_money_saldo 641 641 0 0.00 0 1000 8 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 11 5 0 0
v_add_invoice_to_stock 9244 9022 222 2.40 0 9824 18 0 222
v_all_customers 34264 34264 0 0.00 0 20 1 0 0
v_all_suppliers 27642 27642 0 0.00 0 26 1 0 0
v_all_wares 37266 37266 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 313 7 0 0
v_cancel_client_order 3224 3224 0 0.00 0 2212 7 0 0
v_cancel_customer_prepayment 1132 1132 0 0.00 0 969 8 0 0
v_cancel_customer_reserve 1266 1266 0 0.00 0 2014 6 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 2306 8 0 0
v_cancel_supplier_invoice 692 678 14 2.02 0 703 13 0 14
v_cancel_supplier_order 810 810 0 0.00 0 653 7 0 0
v_cancel_write_off 1854 1854 0 0.00 0 2550 18 0 0
v_max_id_clo_ord 4996 4996 0 0.00 0 1356 2 0 0
v_max_id_clo_res 964 964 0 0.00 0 2642 22 0 0
v_max_id_ord_sup 4926 4926 0 0.00 0 4415 4 0 0
v_max_non_paid_invoice 1426 1426 0 0.00 0 1041 3 0 0
v_max_non_paid_realizn 3303 3303 0 0.00 0 1293 3 0 0
v_min_id_clo_ord 4996 4996 0 0.00 0 6715 7 0 0
v_min_id_clo_res 964 964 0 0.00 0 7146 16 0 0
v_min_id_ord_sup 4926 4926 0 0.00 0 4372 8 0 0
v_min_non_paid_invoice 5325 5325 0 0.00 0 5307 14 0 0
v_min_non_paid_realizn 6509 6509 0 0.00 0 4807 14 0 0
v_our_firm 9414 9414 0 0.00 0 19 1 0 0
v_reserve_write_off 18174 18174 0 0.00 0 2566 6 0 0
x_lock_depdocs_on_canc_invoice 10923 10923 0 0.00 0 27194 158 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 15809 563 0 0
x_make_qstorno_1000_1200 4997 4997 0 0.00 2 27757 1538 0 0
x_make_qstorno_1200_2000 4935 4935 0 0.00 2 91013 4343 0 0
x_make_qstorno_2100_3300 424196 424196 0 0.00 0 127591 182 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-08-2020 09:54:45 to 19-08-2020 09:54:49.

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
07:23:58 1 8192000000 8796612016 8807043072 3144832 0.038 1 1 2 0 3242128 61344 35952
07:24:59 12263 8192000000 9711310048 10000977920 791698816 8.812 100 90 180 0 820757392 7446272 21612304
07:26:17 19012 8192000000 9744816128 10045865984 824860592 9.147 100 87 174 0 855747216 10301376 20585248
07:27:43 28595 8192000000 9791927680 10103730176 862534432 9.525 100 96 192 0 902541456 17675888 22331136
07:29:22 14489 8192000000 9843888592 10166677504 895654400 9.855 100 79 158 1 951011264 37741136 17615728
07:30:45 11832 8192000000 9900240832 10237067264 930030624 10.195 100 80 160 1 1016095600 68555840 17509136
07:31:58 8001 8192000000 9922525824 10270629888 971930208 10.606 100 71 142 1 1037321232 50045504 15345520
07:33:12 5347 8192000000 9967453056 10321108992 1003710592 10.914 100 82 162 2 1086333520 66677616 15945312
07:34:20 2608 8192000000 9984094304 10350133248 1019679376 11.069 100 74 148 2 1099737040 64409488 15648176
07:35:25 2033 8192000000 10013302352 10378592256 1047676720 11.338 100 94 187 0 1129463856 62701680 19085456
07:36:29 2035 8192000000 10030676656 10399326208 1063756704 11.492 100 90 178 0 1138299168 57008832 17533632
07:37:34 600 8192000000 10057568800 10430500864 1069480384 11.547 100 82 166 2 1171521808 85082864 16958560
07:38:38 4917 8192000000 9978917584 10376728576 1072504592 11.576 100 27 55 1 1096680176 18778976 5396608
07:39:44 7221 8192000000 9984385520 10378215424 1081850000 11.665 100 20 40 0 1113454912 27124576 4480336
07:40:51 70490 8192000000 9995837088 10411724800 1086331440 11.708 100 24 48 2 1110590032 18842944 5415648
07:43:04 8824 8192000000 10012443088 10409144320 1096169776 11.801 100 30 58 2 1126503536 23433056 6900704
07:44:13 6095 8192000000 10007430736 10402086912 1078909440 11.637 99 43 86 3 1108812448 20254480 9648528
07:45:21 11084 8192000000 9997449072 10399805440 1075384784 11.603 99 28 55 3 1107580816 26070896 6125136
07:46:35 26403 8192000000 9960132912 10346881024 1035909520 11.225 97 34 68 2 1059239488 14448496 8881472
07:48:02 3226 8192000000 9973346976 10345959424 1014896080 11.023 99 40 80 0 1068711376 44873824 8941472
07:49:07 5916 8192000000 9917696576 10287243264 996106096 10.841 98 32 65 2 1021166336 18029264 7030976
07:50:15 9585 8192000000 9940948544 10338992128 1022854928 11.100 100 26 50 2 1048780672 20567856 5357888
07:51:25 53793 8192000000 9949689696 10351484928 1032172224 11.189 99 25 50 3 1062580544 25162832 5245488
07:53:20 10499 8192000000 9965387776 10356002816 1066751232 11.521 100 36 70 0 1087396528 13205328 7439968
07:54:34 31357 8192000000 10004026640 10396753920 1084010560 11.686 100 54 107 1 1115752144 19777152 11964432
07:56:12 6870 8192000000 10036476592 10431037440 1098612192 11.824 100 55 111 1 1150388720 39983952 11792576
07:57:24 78985 8192000000 10093011248 10495057920 1108263760 11.916 100 57 115 1 1195408480 74686144 12458576
07:59:48 82197 8192000000 10110631200 10523541504 1123103008 12.056 100 36 70 0 1216706944 85767968 7835968
08:02:25 30193 8192000000 10179403168 10581319680 1133643696 12.156 100 45 90 3 1263809840 119653120 10513024
08:03:59 32441 8192000000 10153900352 10599022592 1145754112 12.270 100 39 78 0 1279536496 124124592 9657792
08:05:42 21506 8192000000 10205153472 10609278976 1159321008 12.397 100 57 111 3 1302506224 131176064 12009152
08:07:08 15609 8192000000 10249944864 10659880960 1168738192 12.485 100 52 102 3 1354310736 173592160 11980384
08:08:31 20893 8192000000 10249474064 10687754240 1174368864 12.538 100 58 115 2 1348886448 161571200 12946384
08:09:57 4928 8192000000 10290760960 10694672384 1182294624 12.612 100 76 150 1 1394870368 195384560 17191184
08:11:10 6046 8192000000 10308694880 10714968064 1192049248 12.702 100 60 119 2 1409366912 203674672 13642992
08:12:27 4572 8192000000 10339860512 10749657088 1187362848 12.659 100 61 120 1 1438053888 236305296 14385744
08:13:37 6549 8192000000 10408374656 10825388032 1199323168 12.770 100 60 120 0 1510561632 296689264 14549200
08:15:05 3930 8192000000 10438493488 10835767296 1168184064 12.480 99 83 166 0 1526377792 339247632 18946096
08:16:15 3821 8192000000 10461210112 10866155520 1173426192 12.529 100 89 176 1 1558478256 364590480 20461584
08:17:28 7363 8192000000 10391049056 10853146624 1167728736 12.476 100 82 163 2 1509289760 322455888 19105136
08:18:49 1710 8192000000 10393923696 10799804416 1175435104 12.548 100 82 160 1 1505953584 311466128 19052352
08:19:58 10207 8192000000 10377926272 10791440384 1146869088 12.280 100 54 108 3 1471991776 311830208 13292480
08:21:20 12042 8192000000 10327611664 10773757952 1135761152 12.176 100 50 100 0 1448099280 299298320 13039808
08:23:03 7445 8192000000 10271669120 10718433280 1111975200 11.951 99 53 106 2 1374662064 250417952 12268912
08:24:25 16858 8192000000 10260099424 10687172608 1118428448 12.012 100 28 56 1 1366001648 240879584 6693616
08:25:57 30475 8192000000 10227190112 10670927872 1123869824 12.064 100 20 40 1 1347112272 218576624 4665824
08:27:34 30709 8192000000 10031043216 10442719232 1069826432 11.550 96 27 54 2 1095122528 19236896 6059200
08:29:07 36304 8192000000 10104143232 10534625280 1103984192 11.875 98 21 43 0 1199287600 90939760 4363648
08:30:49 122086 8192000000 10149294912 10605813760 1110465008 11.937 99 35 70 0 1243377680 124568272 8344400
08:35:25 12121 8192000000 10057430672 10472833024 1090974208 11.752 98 49 94 4 1137571440 36755952 9841280
08:36:41 53089 8192000000 10118437680 10531803136 1114600944 11.976 99 48 94 1 1196729664 72515632 9613088
08:38:50 69368 8192000000 10044939712 10499665920 1095396432 11.794 98 9 18 0 1156327584 59003616 1927536
08:41:01 83343 8192000000 10037305216 10490777600 1074692128 11.597 97 13 26 1 1102857440 25411904 2753408
08:43:33 74161 8192000000 10076876928 10531803136 1111328704 11.945 100 23 46 0 1185906464 69137888 5439872
08:47:14 18923 8192000000 10055493984 10481049600 1111987136 11.951 100 21 42 2 1160922368 44681552 4253680
08:48:46 58097 8192000000 10043174240 10509062144 1115236912 11.982 100 7 14 2 1142730240 26200656 1292672
08:50:44 39376 8192000000 10053625248 10482679808 1125088496 12.075 100 19 38 2 1141318256 11866064 4363696
08:52:42 31108 8192000000 10137984224 10570473472 1149033152 12.300 100 32 64 0 1241561328 85440608 7087568
08:54:20 50974 8192000000 10131067776 10629062656 1127521216 12.098 99 18 35 2 1246493312 115455392 3516704
08:56:53 45748 8192000000 10112473664 10540871680 1126017600 12.084 99 36 72 1 1182260576 49320544 6922432
08:58:44 42188 8192000000 10109137072 10558742528 1129082816 12.113 100 25 50 0 1213194512 78975968 5135728
09:00:31 86057 8192000000 10090612832 10606567424 1092793728 11.769 96 7 14 2 1178728032 84329728 1604576
09:03:06 151756 8192000000 10100921728 10568237056 1111967184 11.951 98 17 34 0 1167992848 52517184 3508480
09:06:54 59884 8192000000 10039642432 10524274688 1103066192 11.867 98 16 32 1 1144767232 38594688 3106352
09:08:54 70787 8192000000 10055026128 10511106048 1066020384 11.514 95 8 16 0 1117936480 50165248 1750848
09:11:05 66078 8192000000 10102794720 10568667136 1119754560 12.025 100 15 30 1 1192622736 69426496 3441680
09:13:13 63038 8192000000 10023577904 10489724928 1055753936 11.416 94 8 15 2 1107567920 50243680 1570304
09:15:19 38599 8192000000 10063215520 10483826688 1071018704 11.562 97 32 63 2 1122140864 44819504 6302656
09:17:05 32064 8192000000 10121925104 10552279040 1111970576 11.951 100 39 80 2 1214353088 94263808 8118704
09:18:47 46541 8192000000 10018982864 10450366464 1053444864 11.394 95 14 28 2 1092516496 36469632 2602000
09:20:40 13028 8192000000 10043789072 10447638528 1076498944 11.614 98 54 109 1 1114595072 27124384 10971744
09:21:56 51297 8192000000 10099332096 10538672128 1081484144 11.662 98 35 70 1 1166908576 78128544 7295888
09:23:47 94789 8192000000 10055111584 10543611904 1104549024 11.881 99 11 22 0 1157639408 50751008 2339376
09:27:00 52195 8192000000 10046697488 10528550912 1101504192 11.852 98 7 14 0 1166455552 63783568 1167792
09:28:54 27804 8192000000 10034077520 10470559744 1111462368 11.946 99 16 32 1 1139157472 23946272 3748832
09:30:24 58119 8192000000 10044490944 10480357376 1072927088 11.580 96 23 45 3 1101106576 23376112 4803376
09:32:23 65462 8192000000 10055916400 10481778688 1093471744 11.776 98 18 36 0 1130562432 33551728 3538960
09:34:37 100270 8192000000 10066346672 10513584128 1100854144 11.846 98 11 22 0 1143198672 40122320 2222208
09:37:18 67655 8192000000 10089990448 10506940416 1112179536 11.953 99 14 27 1 1179653424 64788784 2685104
09:39:31 83495 8192000000 10051648208 10486992896 1115004640 11.980 99 18 35 1 1160908832 41856336 4047856
09:41:55 90879 8192000000 10053880544 10527297536 1125889936 12.083 100 19 38 1 1157967392 28107056 3970400
09:46:19 85341 8192000000 10120576000 10592784384 1132852416 12.148 99 13 26 1 1227946176 92505648 2588112
09:48:45 48207 8192000000 10047871568 10489929728 1119275216 12.020 99 9 18 0 1161992912 40860832 1856864
09:50:34 61255 8192000000 10092292416 10527006720 1091062832 11.753 96 27 54 1 1148122208 51422080 5637296
09:52:39 6152 8192000000 10093342224 10502131712 1118114720 12.009 99 68 136 4 1163905152 31946128 13844304
09:53:50 15350 8192000000 9018998112 9263390720 163208752 1.953 15 1 2 0 172661920 9390704 62464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-08-2020 09:54:50 to 19-08-2020 09:55:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 111 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 19-08-2020 09:55:05 to 19-08-2020 09:55:07.

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          218983
MON$OLDEST_ACTIVE               218984
MON$OLDEST_SNAPSHOT             218984
MON$NEXT_TRANSACTION            218984
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                       2071584
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             674
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 ---
--- end of diff output ---

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

19.08.20 09:55:09. There are 3 logs in /var/tmp/logs.oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-08-19 09:55:11 NO_SEVERE_ERRORS_FOUND
19.08.20 09:55:13. End of report.